{"level":"warn","ts":"2026-07-20T18:14:58.928595Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-20T18:14:58.928742Z","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-07-20T18:14:58.928923Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-20T18:14:58.928973Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-07-20T18:14:58.92911Z","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-07-20T18:14:58.930555Z","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-07-20T18:14:58.930752Z","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-07-20T18:14:58.933079Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.831436ms"} {"level":"info","ts":"2026-07-20T18:14:58.94138Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-07-20T18:14:58.941815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-07-20T18:14:58.941916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-07-20T18:14:58.941972Z","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-07-20T18:14:58.942042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-07-20T18:14:58.942138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-07-20T18:14:58.950583Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-20T18:14:58.952554Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-20T18:14:58.957677Z","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-07-20T18:14:58.960159Z","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-07-20T18:14:58.960363Z","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-07-20T18:14:58.960492Z","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-07-20T18:14:58.96053Z","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-07-20T18:14:58.960544Z","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-07-20T18:14:58.961503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-07-20T18:14:58.964244Z","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-07-20T18:14:58.966417Z","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-07-20T18:14:58.966601Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-07-20T18:14:58.966622Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-07-20T18:14:58.966824Z","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-07-20T18:14:58.966884Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-20T18:14:59.643086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-07-20T18:14:59.643152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-20T18:14:59.6432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-07-20T18:14:59.643221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-07-20T18:14:59.643233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-07-20T18:14:59.643254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-07-20T18:14:59.643276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-07-20T18:14:59.644124Z","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-07-20T18:14:59.644298Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-20T18:14:59.644514Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-20T18:14:59.645238Z","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-07-20T18:14:59.64531Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-20T18:14:59.645329Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-20T18:14:59.645809Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-20T18:14:59.645831Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-20T18:14:59.647513Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-20T18:14:59.656365Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-20T18:14:59.658223Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"warn","ts":"2026-07-20T18:15:16.033101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.214503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-20T18:15:16.033282Z","caller":"traceutil/trace.go:171","msg":"trace[2045171169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:397; }","duration":"689.46753ms","start":"2026-07-20T18:15:15.343785Z","end":"2026-07-20T18:15:16.033253Z","steps":["trace[2045171169] 'range keys from in-memory index tree' (duration: 689.078341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:15:16.033278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.311466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-20T18:15:16.03335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:15:15.343761Z","time spent":"689.576813ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-20T18:15:16.033394Z","caller":"traceutil/trace.go:171","msg":"trace[67264416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:397; }","duration":"689.48653ms","start":"2026-07-20T18:15:15.343871Z","end":"2026-07-20T18:15:16.033358Z","steps":["trace[67264416] 'range keys from in-memory index tree' (duration: 689.193043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:15:16.033446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:15:15.343846Z","time spent":"689.588333ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-20T18:15:16.033585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.202876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:15:16.033615Z","caller":"traceutil/trace.go:171","msg":"trace[339127902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:397; }","duration":"658.255217ms","start":"2026-07-20T18:15:15.375345Z","end":"2026-07-20T18:15:16.0336Z","steps":["trace[339127902] 'range keys from in-memory index tree' (duration: 658.136204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:15:16.033639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:15:15.37533Z","time spent":"658.304098ms","remote":"127.0.0.1:41492","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-07-20T18:15:16.487793Z","caller":"traceutil/trace.go:171","msg":"trace[808654448] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"448.459843ms","start":"2026-07-20T18:15:16.039306Z","end":"2026-07-20T18:15:16.487765Z","steps":["trace[808654448] 'process raft request' (duration: 448.292789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:15:16.488543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:15:16.039289Z","time spent":"448.582175ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:15:17.464496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.731546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:15:17.464606Z","caller":"traceutil/trace.go:171","msg":"trace[1507714205] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:408; }","duration":"660.052732ms","start":"2026-07-20T18:15:16.804536Z","end":"2026-07-20T18:15:17.464589Z","steps":["trace[1507714205] 'read index received' (duration: 52.802µs)","trace[1507714205] 'applied index is now lower than readState.Index' (duration: 659.99875ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:15:17.464628Z","caller":"traceutil/trace.go:171","msg":"trace[1316973472] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"1.423669267s","start":"2026-07-20T18:15:16.040934Z","end":"2026-07-20T18:15:17.464603Z","steps":["trace[1316973472] 'process raft request' (duration: 708.591142ms)","trace[1316973472] 'compare' (duration: 714.577062ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:15:17.464724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:15:16.040906Z","time spent":"1.42377337s","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:15:17.464786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.26174ms","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-07-20T18:15:17.464818Z","caller":"traceutil/trace.go:171","msg":"trace[494157927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:399; }","duration":"295.335452ms","start":"2026-07-20T18:15:17.169473Z","end":"2026-07-20T18:15:17.464809Z","steps":["trace[494157927] 'agreement among raft nodes before linearized reading' (duration: 295.289311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:15:17.464733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.204126ms","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-07-20T18:15:17.464971Z","caller":"traceutil/trace.go:171","msg":"trace[1879237728] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:399; }","duration":"660.470343ms","start":"2026-07-20T18:15:16.804486Z","end":"2026-07-20T18:15:17.464956Z","steps":["trace[1879237728] 'agreement among raft nodes before linearized reading' (duration: 660.194256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:15:17.465009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:15:16.804459Z","time spent":"660.540935ms","remote":"127.0.0.1:41774","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-07-20T18:15:17.465071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.499379ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:15:17.465149Z","caller":"traceutil/trace.go:171","msg":"trace[2115064748] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:399; }","duration":"243.579371ms","start":"2026-07-20T18:15:17.22156Z","end":"2026-07-20T18:15:17.46514Z","steps":["trace[2115064748] 'agreement among raft nodes before linearized reading' (duration: 243.491749ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:15:37.203692Z","caller":"traceutil/trace.go:171","msg":"trace[1660016295] transaction","detail":"{read_only:false; number_of_response:1; response_revision:559; }","duration":"161.579294ms","start":"2026-07-20T18:15:37.042087Z","end":"2026-07-20T18:15:37.203666Z","steps":["trace[1660016295] 'process raft request' (duration: 156.642864ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:15:37.20527Z","caller":"traceutil/trace.go:171","msg":"trace[1826930847] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"152.133944ms","start":"2026-07-20T18:15:37.053123Z","end":"2026-07-20T18:15:37.205257Z","steps":["trace[1826930847] 'process raft request' (duration: 151.959109ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:15:37.205638Z","caller":"traceutil/trace.go:171","msg":"trace[907407555] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"100.10106ms","start":"2026-07-20T18:15:37.105522Z","end":"2026-07-20T18:15:37.205623Z","steps":["trace[907407555] 'process raft request' (duration: 99.768332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:15:38.210987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.930938ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:15:38.211165Z","caller":"traceutil/trace.go:171","msg":"trace[853392446] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:579; }","duration":"549.134263ms","start":"2026-07-20T18:15:37.662008Z","end":"2026-07-20T18:15:38.211142Z","steps":["trace[853392446] 'range keys from in-memory index tree' (duration: 548.906297ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:15:38.219843Z","caller":"traceutil/trace.go:171","msg":"trace[835735240] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"554.78279ms","start":"2026-07-20T18:15:37.665028Z","end":"2026-07-20T18:15:38.219811Z","steps":["trace[835735240] 'process raft request' (duration: 548.5758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:15:38.219976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:15:37.665001Z","time spent":"554.918343ms","remote":"127.0.0.1:41552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-20T18:15:44.223872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.981597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:15:44.224214Z","caller":"traceutil/trace.go:171","msg":"trace[896791762] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"142.859609ms","start":"2026-07-20T18:15:44.081316Z","end":"2026-07-20T18:15:44.224176Z","steps":["trace[896791762] 'process raft request' (duration: 14.473602ms)","trace[896791762] 'compare' (duration: 127.782942ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:15:44.2243Z","caller":"traceutil/trace.go:171","msg":"trace[750991181] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"140.859801ms","start":"2026-07-20T18:15:44.083417Z","end":"2026-07-20T18:15:44.224277Z","steps":["trace[750991181] 'process raft request' (duration: 140.797429ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:15:44.224624Z","caller":"traceutil/trace.go:171","msg":"trace[1792842125] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"142.89638ms","start":"2026-07-20T18:15:44.08171Z","end":"2026-07-20T18:15:44.224607Z","steps":["trace[1792842125] 'process raft request' (duration: 142.422348ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:15:44.224705Z","caller":"traceutil/trace.go:171","msg":"trace[995603092] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"142.958362ms","start":"2026-07-20T18:15:44.081692Z","end":"2026-07-20T18:15:44.224651Z","steps":["trace[995603092] 'process raft request' (duration: 142.341867ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:15:44.746416Z","caller":"traceutil/trace.go:171","msg":"trace[291180087] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"105.616394ms","start":"2026-07-20T18:15:44.640768Z","end":"2026-07-20T18:15:44.746384Z","steps":["trace[291180087] 'process raft request' (duration: 105.499331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:15:45.598502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.574935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:15:45.598615Z","caller":"traceutil/trace.go:171","msg":"trace[83579154] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"221.027907ms","start":"2026-07-20T18:15:45.377569Z","end":"2026-07-20T18:15:45.598597Z","steps":["trace[83579154] 'read index received' (duration: 103.207356ms)","trace[83579154] 'applied index is now lower than readState.Index' (duration: 117.819051ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:15:45.598653Z","caller":"traceutil/trace.go:171","msg":"trace[330423002] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"661.319367ms","start":"2026-07-20T18:15:44.937296Z","end":"2026-07-20T18:15:45.598615Z","steps":["trace[330423002] 'process raft request' (duration: 543.544897ms)","trace[330423002] 'compare' (duration: 117.440132ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:15:45.598759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.181361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-20T18:15:45.598796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:15:44.937272Z","time spent":"661.45ms","remote":"127.0.0.1:41774","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-07-20T18:15:45.598806Z","caller":"traceutil/trace.go:171","msg":"trace[1035385215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"221.252782ms","start":"2026-07-20T18:15:45.377536Z","end":"2026-07-20T18:15:45.598789Z","steps":["trace[1035385215] 'agreement among raft nodes before linearized reading' (duration: 221.17915ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:15:45.742604Z","caller":"traceutil/trace.go:171","msg":"trace[95345811] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:653; }","duration":"123.352635ms","start":"2026-07-20T18:15:45.619223Z","end":"2026-07-20T18:15:45.742575Z","steps":["trace[95345811] 'read index received' (duration: 41.076567ms)","trace[95345811] 'applied index is now lower than readState.Index' (duration: 82.272318ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:15:45.742823Z","caller":"traceutil/trace.go:171","msg":"trace[1470147449] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"139.740603ms","start":"2026-07-20T18:15:45.603062Z","end":"2026-07-20T18:15:45.742803Z","steps":["trace[1470147449] 'process raft request' (duration: 117.194285ms)","trace[1470147449] 'compare' (duration: 22.187009ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:15:45.742989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.744805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-20T18:15:45.743151Z","caller":"traceutil/trace.go:171","msg":"trace[1324362364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"123.867308ms","start":"2026-07-20T18:15:45.619212Z","end":"2026-07-20T18:15:45.743079Z","steps":["trace[1324362364] 'agreement among raft nodes before linearized reading' (duration: 123.476258ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:15:47.222231Z","caller":"traceutil/trace.go:171","msg":"trace[1496611172] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"467.763368ms","start":"2026-07-20T18:15:46.754442Z","end":"2026-07-20T18:15:47.222205Z","steps":["trace[1496611172] 'process raft request' (duration: 467.626995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:15:47.222369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:15:46.754413Z","time spent":"467.88988ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:15:48.818159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.503799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:15:48.818229Z","caller":"traceutil/trace.go:171","msg":"trace[673814530] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:666; }","duration":"240.636832ms","start":"2026-07-20T18:15:48.577574Z","end":"2026-07-20T18:15:48.818211Z","steps":["trace[673814530] 'range keys from in-memory index tree' (duration: 240.379836ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:15:58.747269Z","caller":"traceutil/trace.go:171","msg":"trace[231797940] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"267.504925ms","start":"2026-07-20T18:15:58.479743Z","end":"2026-07-20T18:15:58.747248Z","steps":["trace[231797940] 'process raft request' (duration: 267.368922ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:16:01.677457Z","caller":"traceutil/trace.go:171","msg":"trace[393524341] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"302.182037ms","start":"2026-07-20T18:16:01.375244Z","end":"2026-07-20T18:16:01.677426Z","steps":["trace[393524341] 'read index received' (duration: 301.767237ms)","trace[393524341] 'applied index is now lower than readState.Index' (duration: 413.5µs)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:16:01.677615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.344181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:16:01.677666Z","caller":"traceutil/trace.go:171","msg":"trace[307417108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:786; }","duration":"302.409383ms","start":"2026-07-20T18:16:01.375237Z","end":"2026-07-20T18:16:01.677647Z","steps":["trace[307417108] 'agreement among raft nodes before linearized reading' (duration: 302.31763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:16:01.677723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:16:01.37517Z","time spent":"302.539816ms","remote":"127.0.0.1:41476","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-07-20T18:16:01.677702Z","caller":"traceutil/trace.go:171","msg":"trace[2144836140] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"398.199898ms","start":"2026-07-20T18:16:01.279483Z","end":"2026-07-20T18:16:01.677682Z","steps":["trace[2144836140] 'process raft request' (duration: 397.768128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:16:01.678067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:16:01.279446Z","time spent":"398.495346ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:16:01.724199Z","caller":"traceutil/trace.go:171","msg":"trace[1098594101] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"217.781428ms","start":"2026-07-20T18:16:01.506394Z","end":"2026-07-20T18:16:01.724176Z","steps":["trace[1098594101] 'process raft request' (duration: 217.655225ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:16:56.072068Z","caller":"traceutil/trace.go:171","msg":"trace[1473505696] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1104; }","duration":"103.625856ms","start":"2026-07-20T18:16:55.968422Z","end":"2026-07-20T18:16:56.072048Z","steps":["trace[1473505696] 'read index received' (duration: 103.499003ms)","trace[1473505696] 'applied index is now lower than readState.Index' (duration: 125.733µs)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:16:56.072227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.79584ms","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-07-20T18:16:56.072202Z","caller":"traceutil/trace.go:171","msg":"trace[1330469777] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"137.170296ms","start":"2026-07-20T18:16:55.93499Z","end":"2026-07-20T18:16:56.07216Z","steps":["trace[1330469777] 'process raft request' (duration: 136.940501ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:16:56.072264Z","caller":"traceutil/trace.go:171","msg":"trace[57971826] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1065; }","duration":"103.874142ms","start":"2026-07-20T18:16:55.96838Z","end":"2026-07-20T18:16:56.072254Z","steps":["trace[57971826] 'agreement among raft nodes before linearized reading' (duration: 103.772869ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:17:02.235813Z","caller":"traceutil/trace.go:171","msg":"trace[1742838175] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"134.571174ms","start":"2026-07-20T18:17:02.101223Z","end":"2026-07-20T18:17:02.235795Z","steps":["trace[1742838175] 'read index received' (duration: 134.451301ms)","trace[1742838175] 'applied index is now lower than readState.Index' (duration: 117.533µs)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:17:02.235837Z","caller":"traceutil/trace.go:171","msg":"trace[1806271988] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"135.564267ms","start":"2026-07-20T18:17:02.100248Z","end":"2026-07-20T18:17:02.235812Z","steps":["trace[1806271988] 'process raft request' (duration: 135.442334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:02.236005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.775829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-20T18:17:02.236031Z","caller":"traceutil/trace.go:171","msg":"trace[1319024861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1084; }","duration":"134.8515ms","start":"2026-07-20T18:17:02.101171Z","end":"2026-07-20T18:17:02.236022Z","steps":["trace[1319024861] 'agreement among raft nodes before linearized reading' (duration: 134.729718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:02.236057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.700214ms","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-07-20T18:17:02.236092Z","caller":"traceutil/trace.go:171","msg":"trace[384807704] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1084; }","duration":"131.765016ms","start":"2026-07-20T18:17:02.104317Z","end":"2026-07-20T18:17:02.236082Z","steps":["trace[384807704] 'agreement among raft nodes before linearized reading' (duration: 131.648193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:02.236345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.564355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-20T18:17:02.236458Z","caller":"traceutil/trace.go:171","msg":"trace[182574201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1084; }","duration":"132.720659ms","start":"2026-07-20T18:17:02.103706Z","end":"2026-07-20T18:17:02.236426Z","steps":["trace[182574201] 'agreement among raft nodes before linearized reading' (duration: 132.272688ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:17:02.642047Z","caller":"traceutil/trace.go:171","msg":"trace[485667126] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"401.516097ms","start":"2026-07-20T18:17:02.240493Z","end":"2026-07-20T18:17:02.642009Z","steps":["trace[485667126] 'process raft request' (duration: 334.558738ms)","trace[485667126] 'compare' (duration: 66.549709ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:17:02.642176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:02.240478Z","time spent":"401.631321ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:17:03.015005Z","caller":"traceutil/trace.go:171","msg":"trace[36717516] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1125; }","duration":"639.779398ms","start":"2026-07-20T18:17:02.375208Z","end":"2026-07-20T18:17:03.014987Z","steps":["trace[36717516] 'read index received' (duration: 199.854662ms)","trace[36717516] 'applied index is now lower than readState.Index' (duration: 439.924116ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:17:03.015048Z","caller":"traceutil/trace.go:171","msg":"trace[2101434606] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"772.847785ms","start":"2026-07-20T18:17:02.242183Z","end":"2026-07-20T18:17:03.015031Z","steps":["trace[2101434606] 'process raft request' (duration: 772.752713ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:17:03.015084Z","caller":"traceutil/trace.go:171","msg":"trace[1482147313] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"534.334149ms","start":"2026-07-20T18:17:02.480742Z","end":"2026-07-20T18:17:03.015076Z","steps":["trace[1482147313] 'process raft request' (duration: 534.216756ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:17:03.015065Z","caller":"traceutil/trace.go:171","msg":"trace[498850618] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"774.514816ms","start":"2026-07-20T18:17:02.240501Z","end":"2026-07-20T18:17:03.015015Z","steps":["trace[498850618] 'process raft request' (duration: 774.338211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:03.015148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:02.480716Z","time spent":"534.38319ms","remote":"127.0.0.1:41774","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-07-20T18:17:03.015149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:02.242163Z","time spent":"772.929947ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:17:03.015216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:02.240473Z","time spent":"774.657449ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:17:03.015319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.110856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:17:03.01534Z","caller":"traceutil/trace.go:171","msg":"trace[1943521143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1088; }","duration":"640.172647ms","start":"2026-07-20T18:17:02.375162Z","end":"2026-07-20T18:17:03.015334Z","steps":["trace[1943521143] 'agreement among raft nodes before linearized reading' (duration: 640.136496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:03.01536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:02.375137Z","time spent":"640.220099ms","remote":"127.0.0.1:41492","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-07-20T18:17:03.015454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.240171ms","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-07-20T18:17:03.015478Z","caller":"traceutil/trace.go:171","msg":"trace[839198801] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:1088; }","duration":"437.302183ms","start":"2026-07-20T18:17:02.578168Z","end":"2026-07-20T18:17:03.01547Z","steps":["trace[839198801] 'agreement among raft nodes before linearized reading' (duration: 437.21079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:03.015498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:02.578146Z","time spent":"437.346974ms","remote":"127.0.0.1:41532","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-07-20T18:17:03.015582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.436013ms","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-07-20T18:17:03.015602Z","caller":"traceutil/trace.go:171","msg":"trace[2065563338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1088; }","duration":"295.495475ms","start":"2026-07-20T18:17:02.720101Z","end":"2026-07-20T18:17:03.015596Z","steps":["trace[2065563338] 'agreement among raft nodes before linearized reading' (duration: 295.434603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:03.015655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.879602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-20T18:17:03.015675Z","caller":"traceutil/trace.go:171","msg":"trace[609042041] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1088; }","duration":"340.945423ms","start":"2026-07-20T18:17:02.674724Z","end":"2026-07-20T18:17:03.015669Z","steps":["trace[609042041] 'agreement among raft nodes before linearized reading' (duration: 340.895882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:03.015692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:02.674696Z","time spent":"340.991325ms","remote":"127.0.0.1:41856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-07-20T18:17:25.32637Z","caller":"traceutil/trace.go:171","msg":"trace[512449166] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1205; }","duration":"233.513825ms","start":"2026-07-20T18:17:25.092833Z","end":"2026-07-20T18:17:25.326347Z","steps":["trace[512449166] 'read index received' (duration: 233.27286ms)","trace[512449166] 'applied index is now lower than readState.Index' (duration: 239.735µs)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:17:25.326488Z","caller":"traceutil/trace.go:171","msg":"trace[849498775] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"245.353692ms","start":"2026-07-20T18:17:25.081124Z","end":"2026-07-20T18:17:25.326477Z","steps":["trace[849498775] 'process raft request' (duration: 245.056284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:25.327094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.885675ms","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-07-20T18:17:25.327158Z","caller":"traceutil/trace.go:171","msg":"trace[616244471] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1160; }","duration":"226.995048ms","start":"2026-07-20T18:17:25.100149Z","end":"2026-07-20T18:17:25.327144Z","steps":["trace[616244471] 'agreement among raft nodes before linearized reading' (duration: 226.830704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:25.327327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.471808ms","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-07-20T18:17:25.327389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.699476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-20T18:17:25.327413Z","caller":"traceutil/trace.go:171","msg":"trace[2076031015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1160; }","duration":"218.741897ms","start":"2026-07-20T18:17:25.108665Z","end":"2026-07-20T18:17:25.327407Z","steps":["trace[2076031015] 'agreement among raft nodes before linearized reading' (duration: 218.675985ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:17:25.327417Z","caller":"traceutil/trace.go:171","msg":"trace[1241278225] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1160; }","duration":"234.618782ms","start":"2026-07-20T18:17:25.092779Z","end":"2026-07-20T18:17:25.327398Z","steps":["trace[1241278225] 'agreement among raft nodes before linearized reading' (duration: 233.827753ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:17:25.690336Z","caller":"traceutil/trace.go:171","msg":"trace[420025133] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"358.606831ms","start":"2026-07-20T18:17:25.331699Z","end":"2026-07-20T18:17:25.690306Z","steps":["trace[420025133] 'process raft request' (duration: 358.444567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:25.690502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:25.331681Z","time spent":"358.734425ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:17:25.718076Z","caller":"traceutil/trace.go:171","msg":"trace[661284686] linearizableReadLoop","detail":"{readStateIndex:1209; appliedIndex:1206; }","duration":"344.13677ms","start":"2026-07-20T18:17:25.373908Z","end":"2026-07-20T18:17:25.718045Z","steps":["trace[661284686] 'read index received' (duration: 316.222306ms)","trace[661284686] 'applied index is now lower than readState.Index' (duration: 27.912904ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:17:25.71811Z","caller":"traceutil/trace.go:171","msg":"trace[603234165] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"385.744286ms","start":"2026-07-20T18:17:25.332345Z","end":"2026-07-20T18:17:25.718089Z","steps":["trace[603234165] 'process raft request' (duration: 385.454789ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:17:25.718167Z","caller":"traceutil/trace.go:171","msg":"trace[1571079474] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"385.552672ms","start":"2026-07-20T18:17:25.332591Z","end":"2026-07-20T18:17:25.718144Z","steps":["trace[1571079474] 'process raft request' (duration: 385.387398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:25.718214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.299635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-20T18:17:25.718242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:25.332299Z","time spent":"385.87174ms","remote":"127.0.0.1:41774","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-07-20T18:17:25.718298Z","caller":"traceutil/trace.go:171","msg":"trace[1995359173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1163; }","duration":"344.410767ms","start":"2026-07-20T18:17:25.373834Z","end":"2026-07-20T18:17:25.718245Z","steps":["trace[1995359173] 'agreement among raft nodes before linearized reading' (duration: 344.323865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:25.7183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:25.332556Z","time spent":"385.674105ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:17:25.718351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:25.373804Z","time spent":"344.53501ms","remote":"127.0.0.1:41492","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-07-20T18:17:26.725573Z","caller":"traceutil/trace.go:171","msg":"trace[38386282] linearizableReadLoop","detail":"{readStateIndex:1210; appliedIndex:1209; }","duration":"350.817801ms","start":"2026-07-20T18:17:26.374731Z","end":"2026-07-20T18:17:26.725548Z","steps":["trace[38386282] 'read index received' (duration: 350.688818ms)","trace[38386282] 'applied index is now lower than readState.Index' (duration: 127.743µs)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:17:26.72565Z","caller":"traceutil/trace.go:171","msg":"trace[1443808971] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"353.473876ms","start":"2026-07-20T18:17:26.372138Z","end":"2026-07-20T18:17:26.725612Z","steps":["trace[1443808971] 'process raft request' (duration: 353.24168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:26.725699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.952934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:17:26.725764Z","caller":"traceutil/trace.go:171","msg":"trace[1263727337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1164; }","duration":"351.025256ms","start":"2026-07-20T18:17:26.374699Z","end":"2026-07-20T18:17:26.725724Z","steps":["trace[1263727337] 'agreement among raft nodes before linearized reading' (duration: 350.947445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:26.725807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:26.374688Z","time spent":"351.110618ms","remote":"127.0.0.1:41492","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-07-20T18:17:26.725885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:26.372117Z","time spent":"353.66332ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:17:27.320643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.538257ms","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-07-20T18:17:27.320722Z","caller":"traceutil/trace.go:171","msg":"trace[649693663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1164; }","duration":"571.65653ms","start":"2026-07-20T18:17:26.749045Z","end":"2026-07-20T18:17:27.320701Z","steps":["trace[649693663] 'range keys from in-memory index tree' (duration: 571.407514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:27.32076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:26.749032Z","time spent":"571.720032ms","remote":"127.0.0.1:41774","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-07-20T18:17:27.325936Z","caller":"traceutil/trace.go:171","msg":"trace[2020349896] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"154.211089ms","start":"2026-07-20T18:17:27.171709Z","end":"2026-07-20T18:17:27.32592Z","steps":["trace[2020349896] 'process raft request' (duration: 154.092006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:27.686264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.048284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:17:27.686424Z","caller":"traceutil/trace.go:171","msg":"trace[525417768] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"360.980967ms","start":"2026-07-20T18:17:27.325401Z","end":"2026-07-20T18:17:27.686382Z","steps":["trace[525417768] 'process raft request' (duration: 218.124103ms)","trace[525417768] 'compare' (duration: 141.87305ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:17:27.68652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:27.325379Z","time spent":"361.087439ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:17:27.696299Z","caller":"traceutil/trace.go:171","msg":"trace[398543437] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"365.224449ms","start":"2026-07-20T18:17:27.331054Z","end":"2026-07-20T18:17:27.696279Z","steps":["trace[398543437] 'process raft request' (duration: 365.102896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:27.696419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:27.331026Z","time spent":"365.329522ms","remote":"127.0.0.1:41774","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-07-20T18:17:50.280789Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844883068291416,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-20T18:17:50.550978Z","caller":"traceutil/trace.go:171","msg":"trace[802520949] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"785.703237ms","start":"2026-07-20T18:17:49.765242Z","end":"2026-07-20T18:17:50.550945Z","steps":["trace[802520949] 'process raft request' (duration: 785.40425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:50.551096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:49.765213Z","time spent":"785.82349ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:17:50.551155Z","caller":"traceutil/trace.go:171","msg":"trace[1321706279] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1344; }","duration":"771.012711ms","start":"2026-07-20T18:17:49.780117Z","end":"2026-07-20T18:17:50.551129Z","steps":["trace[1321706279] 'read index received' (duration: 770.454978ms)","trace[1321706279] 'applied index is now lower than readState.Index' (duration: 555.223µs)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:17:50.551327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.49559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:17:50.551354Z","caller":"traceutil/trace.go:171","msg":"trace[835713922] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1291; }","duration":"429.560782ms","start":"2026-07-20T18:17:50.121786Z","end":"2026-07-20T18:17:50.551347Z","steps":["trace[835713922] 'agreement among raft nodes before linearized reading' (duration: 429.47696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:50.551376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:50.121764Z","time spent":"429.605752ms","remote":"127.0.0.1:41710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-07-20T18:17:50.551392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.00687ms","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-07-20T18:17:50.551413Z","caller":"traceutil/trace.go:171","msg":"trace[1709061351] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1291; }","duration":"661.092142ms","start":"2026-07-20T18:17:49.890315Z","end":"2026-07-20T18:17:50.551407Z","steps":["trace[1709061351] 'agreement among raft nodes before linearized reading' (duration: 660.945408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:50.551435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:49.890281Z","time spent":"661.149504ms","remote":"127.0.0.1:41774","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-07-20T18:17:50.551477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.775594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-20T18:17:50.551544Z","caller":"traceutil/trace.go:171","msg":"trace[1225171121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1291; }","duration":"624.889806ms","start":"2026-07-20T18:17:49.926638Z","end":"2026-07-20T18:17:50.551528Z","steps":["trace[1225171121] 'agreement among raft nodes before linearized reading' (duration: 624.695301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:50.551581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:49.926618Z","time spent":"624.956248ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-20T18:17:50.551597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.434571ms","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-07-20T18:17:50.551733Z","caller":"traceutil/trace.go:171","msg":"trace[471886200] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1291; }","duration":"771.637776ms","start":"2026-07-20T18:17:49.780069Z","end":"2026-07-20T18:17:50.551707Z","steps":["trace[471886200] 'agreement among raft nodes before linearized reading' (duration: 771.167085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:50.551789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:49.780053Z","time spent":"771.725889ms","remote":"127.0.0.1:41774","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":"warn","ts":"2026-07-20T18:17:50.552703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.021581ms","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-07-20T18:17:50.552767Z","caller":"traceutil/trace.go:171","msg":"trace[1676198244] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1291; }","duration":"623.130164ms","start":"2026-07-20T18:17:49.929621Z","end":"2026-07-20T18:17:50.552751Z","steps":["trace[1676198244] 'agreement among raft nodes before linearized reading' (duration: 622.948469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:50.552808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:49.929608Z","time spent":"623.191176ms","remote":"127.0.0.1:41774","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-07-20T18:17:51.110046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.389166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:17:51.112335Z","caller":"traceutil/trace.go:171","msg":"trace[1517737376] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"554.482724ms","start":"2026-07-20T18:17:50.5578Z","end":"2026-07-20T18:17:51.112283Z","steps":["trace[1517737376] 'process raft request' (duration: 244.755631ms)","trace[1517737376] 'compare' (duration: 307.206772ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:17:51.112633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:50.557763Z","time spent":"554.756231ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:17:51.115083Z","caller":"traceutil/trace.go:171","msg":"trace[1467917324] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1344; }","duration":"417.368968ms","start":"2026-07-20T18:17:50.697654Z","end":"2026-07-20T18:17:51.115023Z","steps":["trace[1467917324] 'read index received' (duration: 104.917278ms)","trace[1467917324] 'applied index is now lower than readState.Index' (duration: 312.450229ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:17:51.115583Z","caller":"traceutil/trace.go:171","msg":"trace[1525249936] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"557.593589ms","start":"2026-07-20T18:17:50.557962Z","end":"2026-07-20T18:17:51.115556Z","steps":["trace[1525249936] 'process raft request' (duration: 556.753709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:51.115695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:50.557951Z","time spent":"557.668982ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:17:51.115898Z","caller":"traceutil/trace.go:171","msg":"trace[1354011942] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"557.870366ms","start":"2026-07-20T18:17:50.558006Z","end":"2026-07-20T18:17:51.115877Z","steps":["trace[1354011942] 'process raft request' (duration: 556.912363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:51.115964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:50.557967Z","time spent":"557.961338ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:17:51.116186Z","caller":"traceutil/trace.go:171","msg":"trace[510737092] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"557.820445ms","start":"2026-07-20T18:17:50.55834Z","end":"2026-07-20T18:17:51.116161Z","steps":["trace[510737092] 'process raft request' (duration: 556.630476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:51.116255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:50.558323Z","time spent":"557.896647ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:17:51.116504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.853083ms","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-07-20T18:17:51.116654Z","caller":"traceutil/trace.go:171","msg":"trace[903463553] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1295; }","duration":"418.953926ms","start":"2026-07-20T18:17:50.697598Z","end":"2026-07-20T18:17:51.116552Z","steps":["trace[903463553] 'agreement among raft nodes before linearized reading' (duration: 418.857103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:51.116702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:17:50.697574Z","time spent":"419.11794ms","remote":"127.0.0.1:41694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-07-20T18:17:51.116983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.819523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-20T18:17:51.11703Z","caller":"traceutil/trace.go:171","msg":"trace[285702420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1295; }","duration":"294.881204ms","start":"2026-07-20T18:17:50.82213Z","end":"2026-07-20T18:17:51.117011Z","steps":["trace[285702420] 'agreement among raft nodes before linearized reading' (duration: 294.755341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:17:51.117375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.160479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:17:51.11742Z","caller":"traceutil/trace.go:171","msg":"trace[2030206245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1295; }","duration":"161.23456ms","start":"2026-07-20T18:17:50.956175Z","end":"2026-07-20T18:17:51.117409Z","steps":["trace[2030206245] 'agreement among raft nodes before linearized reading' (duration: 161.161238ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:18:03.323467Z","caller":"traceutil/trace.go:171","msg":"trace[137816851] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"131.396529ms","start":"2026-07-20T18:18:03.192052Z","end":"2026-07-20T18:18:03.323449Z","steps":["trace[137816851] 'process raft request' (duration: 131.287786ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:18:03.353796Z","caller":"traceutil/trace.go:171","msg":"trace[1534374442] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1388; }","duration":"159.529689ms","start":"2026-07-20T18:18:03.194244Z","end":"2026-07-20T18:18:03.353774Z","steps":["trace[1534374442] 'read index received' (duration: 129.222926ms)","trace[1534374442] 'applied index is now lower than readState.Index' (duration: 30.305083ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:18:03.353897Z","caller":"traceutil/trace.go:171","msg":"trace[233480674] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"160.497253ms","start":"2026-07-20T18:18:03.193391Z","end":"2026-07-20T18:18:03.353889Z","steps":["trace[233480674] 'process raft request' (duration: 160.216476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:18:03.354144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.889068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-20T18:18:03.354189Z","caller":"traceutil/trace.go:171","msg":"trace[565863839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1333; }","duration":"159.97628ms","start":"2026-07-20T18:18:03.194202Z","end":"2026-07-20T18:18:03.354178Z","steps":["trace[565863839] 'agreement among raft nodes before linearized reading' (duration: 159.828616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:18:03.35454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.952848ms","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-07-20T18:18:03.354577Z","caller":"traceutil/trace.go:171","msg":"trace[1076989949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1333; }","duration":"155.03569ms","start":"2026-07-20T18:18:03.199533Z","end":"2026-07-20T18:18:03.354569Z","steps":["trace[1076989949] 'agreement among raft nodes before linearized reading' (duration: 154.941208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:18:03.355665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.374788ms","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-07-20T18:18:03.355768Z","caller":"traceutil/trace.go:171","msg":"trace[1215239368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1333; }","duration":"157.512801ms","start":"2026-07-20T18:18:03.198225Z","end":"2026-07-20T18:18:03.355738Z","steps":["trace[1215239368] 'agreement among raft nodes before linearized reading' (duration: 156.087036ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:18:03.471733Z","caller":"traceutil/trace.go:171","msg":"trace[1212819692] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"112.154334ms","start":"2026-07-20T18:18:03.359525Z","end":"2026-07-20T18:18:03.47168Z","steps":["trace[1212819692] 'process raft request' (duration: 50.660726ms)","trace[1212819692] 'compare' (duration: 60.988815ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:18:03.471842Z","caller":"traceutil/trace.go:171","msg":"trace[1241567917] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"111.077997ms","start":"2026-07-20T18:18:03.360735Z","end":"2026-07-20T18:18:03.471813Z","steps":["trace[1241567917] 'process raft request' (duration: 110.840221ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:18:03.471752Z","caller":"traceutil/trace.go:171","msg":"trace[876591544] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"109.678554ms","start":"2026-07-20T18:18:03.362043Z","end":"2026-07-20T18:18:03.471721Z","steps":["trace[876591544] 'process raft request' (duration: 109.633493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:18:05.755122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.61164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:18:05.755238Z","caller":"traceutil/trace.go:171","msg":"trace[1465371218] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1402; }","duration":"380.411383ms","start":"2026-07-20T18:18:05.374806Z","end":"2026-07-20T18:18:05.755218Z","steps":["trace[1465371218] 'read index received' (duration: 187.63793ms)","trace[1465371218] 'applied index is now lower than readState.Index' (duration: 192.772393ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:18:05.755285Z","caller":"traceutil/trace.go:171","msg":"trace[1285559701] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"391.217795ms","start":"2026-07-20T18:18:05.364034Z","end":"2026-07-20T18:18:05.755252Z","steps":["trace[1285559701] 'process raft request' (duration: 198.39243ms)","trace[1285559701] 'compare' (duration: 192.457156ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:18:05.755441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:18:05.364Z","time spent":"391.360568ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:18:05.755412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.605428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:18:05.7564Z","caller":"traceutil/trace.go:171","msg":"trace[367554094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"381.089049ms","start":"2026-07-20T18:18:05.374755Z","end":"2026-07-20T18:18:05.755844Z","steps":["trace[367554094] 'agreement among raft nodes before linearized reading' (duration: 380.542966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:18:05.756495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:18:05.374731Z","time spent":"381.744936ms","remote":"127.0.0.1:41492","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-07-20T18:18:06.178153Z","caller":"traceutil/trace.go:171","msg":"trace[46476664] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1403; }","duration":"422.856211ms","start":"2026-07-20T18:18:05.755264Z","end":"2026-07-20T18:18:06.17812Z","steps":["trace[46476664] 'read index received' (duration: 422.638405ms)","trace[46476664] 'applied index is now lower than readState.Index' (duration: 216.176µs)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:18:06.178207Z","caller":"traceutil/trace.go:171","msg":"trace[782235889] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"783.513425ms","start":"2026-07-20T18:18:05.394671Z","end":"2026-07-20T18:18:06.178185Z","steps":["trace[782235889] 'process raft request' (duration: 783.274029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:18:06.178379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:18:05.394648Z","time spent":"783.622168ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:18:06.178488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.193938ms","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-07-20T18:18:06.178549Z","caller":"traceutil/trace.go:171","msg":"trace[2069198100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1348; }","duration":"702.26834ms","start":"2026-07-20T18:18:05.476265Z","end":"2026-07-20T18:18:06.178533Z","steps":["trace[2069198100] 'agreement among raft nodes before linearized reading' (duration: 702.034054ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:18:06.178599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:18:05.476259Z","time spent":"702.327931ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-20T18:18:06.178724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.784482ms","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-07-20T18:18:06.17881Z","caller":"traceutil/trace.go:171","msg":"trace[640462696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1348; }","duration":"702.932186ms","start":"2026-07-20T18:18:05.47586Z","end":"2026-07-20T18:18:06.178792Z","steps":["trace[640462696] 'agreement among raft nodes before linearized reading' (duration: 702.650419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:18:06.178845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:18:05.475836Z","time spent":"703.003688ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-20T18:18:06.179011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.936336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-20T18:18:06.179091Z","caller":"traceutil/trace.go:171","msg":"trace[1650144675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1348; }","duration":"703.079099ms","start":"2026-07-20T18:18:05.475992Z","end":"2026-07-20T18:18:06.179071Z","steps":["trace[1650144675] 'agreement among raft nodes before linearized reading' (duration: 702.788292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:18:06.179145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:18:05.475955Z","time spent":"703.171022ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-20T18:18:06.179045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.376949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:18:06.179236Z","caller":"traceutil/trace.go:171","msg":"trace[472438719] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1348; }","duration":"143.554693ms","start":"2026-07-20T18:18:06.035659Z","end":"2026-07-20T18:18:06.179213Z","steps":["trace[472438719] 'agreement among raft nodes before linearized reading' (duration: 143.361478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:18:06.179616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.271326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:18:06.179681Z","caller":"traceutil/trace.go:171","msg":"trace[1773381500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1348; }","duration":"422.375269ms","start":"2026-07-20T18:18:05.757287Z","end":"2026-07-20T18:18:06.179663Z","steps":["trace[1773381500] 'agreement among raft nodes before linearized reading' (duration: 421.470837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:18:06.179721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:18:05.757263Z","time spent":"422.44746ms","remote":"127.0.0.1:41476","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-07-20T18:18:07.143572Z","caller":"traceutil/trace.go:171","msg":"trace[828910094] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1408; }","duration":"102.370307ms","start":"2026-07-20T18:18:07.041176Z","end":"2026-07-20T18:18:07.143547Z","steps":["trace[828910094] 'read index received' (duration: 102.116071ms)","trace[828910094] 'applied index is now lower than readState.Index' (duration: 253.016µs)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:18:07.143665Z","caller":"traceutil/trace.go:171","msg":"trace[1325157600] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"510.579273ms","start":"2026-07-20T18:18:06.63305Z","end":"2026-07-20T18:18:07.14363Z","steps":["trace[1325157600] 'process raft request' (duration: 510.324907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:18:07.143829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:18:06.63302Z","time spent":"510.715416ms","remote":"127.0.0.1:41774","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-07-20T18:18:07.143881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.653563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:18:07.144443Z","caller":"traceutil/trace.go:171","msg":"trace[1979155303] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1353; }","duration":"103.285349ms","start":"2026-07-20T18:18:07.041122Z","end":"2026-07-20T18:18:07.144407Z","steps":["trace[1979155303] 'agreement among raft nodes before linearized reading' (duration: 102.651173ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:18:07.618238Z","caller":"traceutil/trace.go:171","msg":"trace[130064312] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"131.02823ms","start":"2026-07-20T18:18:07.487182Z","end":"2026-07-20T18:18:07.61821Z","steps":["trace[130064312] 'process raft request' (duration: 130.866666ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:18:07.90914Z","caller":"traceutil/trace.go:171","msg":"trace[1490850437] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1412; }","duration":"287.429204ms","start":"2026-07-20T18:18:07.621682Z","end":"2026-07-20T18:18:07.909111Z","steps":["trace[1490850437] 'read index received' (duration: 287.240789ms)","trace[1490850437] 'applied index is now lower than readState.Index' (duration: 187.105µs)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:18:07.90917Z","caller":"traceutil/trace.go:171","msg":"trace[1957426959] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"345.58082ms","start":"2026-07-20T18:18:07.563557Z","end":"2026-07-20T18:18:07.909137Z","steps":["trace[1957426959] 'process raft request' (duration: 345.391366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:18:07.909332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:18:07.563531Z","time spent":"345.714024ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-20T18:18:07.909393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.69213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-07-20T18:18:07.909464Z","caller":"traceutil/trace.go:171","msg":"trace[538441118] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1357; }","duration":"287.795093ms","start":"2026-07-20T18:18:07.621652Z","end":"2026-07-20T18:18:07.909447Z","steps":["trace[538441118] 'agreement among raft nodes before linearized reading' (duration: 287.590988ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:18:08.137226Z","caller":"traceutil/trace.go:171","msg":"trace[233979102] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1413; }","duration":"227.991095ms","start":"2026-07-20T18:18:07.909211Z","end":"2026-07-20T18:18:08.137202Z","steps":["trace[233979102] 'read index received' (duration: 141.064543ms)","trace[233979102] 'applied index is now lower than readState.Index' (duration: 86.925342ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:18:08.137323Z","caller":"traceutil/trace.go:171","msg":"trace[1429348131] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"440.21811ms","start":"2026-07-20T18:18:07.697067Z","end":"2026-07-20T18:18:08.137285Z","steps":["trace[1429348131] 'process raft request' (duration: 439.956334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:18:08.137483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:18:07.697045Z","time spent":"440.350263ms","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5640,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:18:08.137515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.961534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-20T18:18:08.137602Z","caller":"traceutil/trace.go:171","msg":"trace[531928254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1358; }","duration":"378.098527ms","start":"2026-07-20T18:18:07.759483Z","end":"2026-07-20T18:18:08.137582Z","steps":["trace[531928254] 'agreement among raft nodes before linearized reading' (duration: 377.803629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:18:08.137654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:18:07.759464Z","time spent":"378.181429ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-20T18:18:08.240348Z","caller":"traceutil/trace.go:171","msg":"trace[1032987840] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"326.362075ms","start":"2026-07-20T18:18:07.913959Z","end":"2026-07-20T18:18:08.240321Z","steps":["trace[1032987840] 'process raft request' (duration: 326.192931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:18:08.240498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:18:07.913944Z","time spent":"326.469828ms","remote":"127.0.0.1:41928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:18:08.240499Z","caller":"traceutil/trace.go:171","msg":"trace[1548454620] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"327.393701ms","start":"2026-07-20T18:18:07.913076Z","end":"2026-07-20T18:18:08.24047Z","steps":["trace[1548454620] 'process raft request' (duration: 314.780946ms)","trace[1548454620] 'compare' (duration: 11.951329ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:18:08.240549Z","caller":"traceutil/trace.go:171","msg":"trace[1612752134] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"284.80243ms","start":"2026-07-20T18:18:07.955715Z","end":"2026-07-20T18:18:08.240517Z","steps":["trace[1612752134] 'process raft request' (duration: 284.570354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:18:08.240731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:18:07.913047Z","time spent":"327.554784ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-20T18:18:08.258262Z","caller":"traceutil/trace.go:171","msg":"trace[154810763] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"115.428721ms","start":"2026-07-20T18:18:08.142796Z","end":"2026-07-20T18:18:08.258225Z","steps":["trace[154810763] 'process raft request' (duration: 115.121264ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:18:12.801637Z","caller":"traceutil/trace.go:171","msg":"trace[851731669] linearizableReadLoop","detail":"{readStateIndex:1455; appliedIndex:1454; }","duration":"143.384679ms","start":"2026-07-20T18:18:12.658218Z","end":"2026-07-20T18:18:12.801602Z","steps":["trace[851731669] 'read index received' (duration: 143.154824ms)","trace[851731669] 'applied index is now lower than readState.Index' (duration: 227.985µs)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:18:12.801806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.560423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:18:12.801861Z","caller":"traceutil/trace.go:171","msg":"trace[877526518] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1397; }","duration":"143.681756ms","start":"2026-07-20T18:18:12.658165Z","end":"2026-07-20T18:18:12.801847Z","steps":["trace[877526518] 'agreement among raft nodes before linearized reading' (duration: 143.542353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:18:13.292165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.556884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:18:13.292275Z","caller":"traceutil/trace.go:171","msg":"trace[1978992876] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"489.397ms","start":"2026-07-20T18:18:12.802857Z","end":"2026-07-20T18:18:13.292254Z","steps":["trace[1978992876] 'process raft request' (duration: 228.656021ms)","trace[1978992876] 'compare' (duration: 260.430051ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:18:13.292353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:18:12.802834Z","time spent":"489.479532ms","remote":"127.0.0.1:41532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:18:13.293063Z","caller":"traceutil/trace.go:171","msg":"trace[379700746] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"130.005936ms","start":"2026-07-20T18:18:13.163029Z","end":"2026-07-20T18:18:13.293035Z","steps":["trace[379700746] 'process raft request' (duration: 129.837651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:19:45.219096Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844883068292708,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-20T18:19:45.753918Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844883068292708,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-20T18:19:46.254685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844883068292708,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-20T18:19:46.29437Z","caller":"traceutil/trace.go:171","msg":"trace[1504500111] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"1.58149511s","start":"2026-07-20T18:19:44.712852Z","end":"2026-07-20T18:19:46.294347Z","steps":["trace[1504500111] 'process raft request' (duration: 1.581347086s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:19:46.294514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:19:44.712831Z","time spent":"1.581606292s","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:19:46.297919Z","caller":"traceutil/trace.go:171","msg":"trace[1730355986] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"1.583925878s","start":"2026-07-20T18:19:44.71398Z","end":"2026-07-20T18:19:46.297906Z","steps":["trace[1730355986] 'process raft request' (duration: 1.583834875s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:19:46.298017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:19:44.713953Z","time spent":"1.58400133s","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:19:46.298118Z","caller":"traceutil/trace.go:171","msg":"trace[2009320658] linearizableReadLoop","detail":"{readStateIndex:1917; appliedIndex:1914; }","duration":"1.580091106s","start":"2026-07-20T18:19:44.717995Z","end":"2026-07-20T18:19:46.298086Z","steps":["trace[2009320658] 'read index received' (duration: 1.576198472s)","trace[2009320658] 'applied index is now lower than readState.Index' (duration: 3.891284ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:19:46.298287Z","caller":"traceutil/trace.go:171","msg":"trace[804582871] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"663.140988ms","start":"2026-07-20T18:19:45.635131Z","end":"2026-07-20T18:19:46.298272Z","steps":["trace[804582871] 'process raft request' (duration: 662.748769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:19:46.298411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:19:45.635095Z","time spent":"663.232441ms","remote":"127.0.0.1:41774","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-07-20T18:19:46.29844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.093131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-20T18:19:46.298468Z","caller":"traceutil/trace.go:171","msg":"trace[2020132278] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1841; }","duration":"715.173943ms","start":"2026-07-20T18:19:45.583286Z","end":"2026-07-20T18:19:46.29846Z","steps":["trace[2020132278] 'agreement among raft nodes before linearized reading' (duration: 715.082451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:19:46.29849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:19:45.583257Z","time spent":"715.228145ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-20T18:19:46.298525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.533196465s","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-07-20T18:19:46.29858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.580593388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-20T18:19:46.298599Z","caller":"traceutil/trace.go:171","msg":"trace[1989393416] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1841; }","duration":"1.580633539s","start":"2026-07-20T18:19:44.71796Z","end":"2026-07-20T18:19:46.298593Z","steps":["trace[1989393416] 'agreement among raft nodes before linearized reading' (duration: 1.580580478s)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:19:46.298599Z","caller":"traceutil/trace.go:171","msg":"trace[1793194251] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1841; }","duration":"1.533315638s","start":"2026-07-20T18:19:44.765267Z","end":"2026-07-20T18:19:46.298583Z","steps":["trace[1793194251] 'agreement among raft nodes before linearized reading' (duration: 1.533099073s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:19:46.298619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:19:44.717947Z","time spent":"1.58066619s","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-20T18:19:46.298647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:19:44.765254Z","time spent":"1.533384479s","remote":"127.0.0.1:41774","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-07-20T18:19:46.29881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.530730835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-20T18:19:46.298827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051053062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-07-20T18:19:46.298836Z","caller":"traceutil/trace.go:171","msg":"trace[2108812103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1841; }","duration":"1.530787017s","start":"2026-07-20T18:19:44.76804Z","end":"2026-07-20T18:19:46.298827Z","steps":["trace[2108812103] 'agreement among raft nodes before linearized reading' (duration: 1.530702095s)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:19:46.298898Z","caller":"traceutil/trace.go:171","msg":"trace[1603447756] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1841; }","duration":"1.051121213s","start":"2026-07-20T18:19:45.247732Z","end":"2026-07-20T18:19:46.298853Z","steps":["trace[1603447756] 'agreement among raft nodes before linearized reading' (duration: 1.05100397s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:19:46.298897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.357431ms","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-07-20T18:19:46.298905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:19:44.768026Z","time spent":"1.530871939s","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-20T18:19:46.298929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:19:45.247714Z","time spent":"1.051207915s","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":546,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-20T18:19:46.298938Z","caller":"traceutil/trace.go:171","msg":"trace[900987639] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1841; }","duration":"492.481193ms","start":"2026-07-20T18:19:45.806445Z","end":"2026-07-20T18:19:46.298926Z","steps":["trace[900987639] 'agreement among raft nodes before linearized reading' (duration: 492.35166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:19:46.298973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:19:45.80642Z","time spent":"492.545975ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-20T18:19:46.299017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.383573ms","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":"info","ts":"2026-07-20T18:19:46.299046Z","caller":"traceutil/trace.go:171","msg":"trace[1997663109] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1841; }","duration":"923.438824ms","start":"2026-07-20T18:19:45.375598Z","end":"2026-07-20T18:19:46.299037Z","steps":["trace[1997663109] 'agreement among raft nodes before linearized reading' (duration: 923.392693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:19:46.299071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:19:45.375582Z","time spent":"923.481566ms","remote":"127.0.0.1:37122","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":"warn","ts":"2026-07-20T18:20:54.798202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.7956ms","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-07-20T18:20:54.798355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.162621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-20T18:20:54.79845Z","caller":"traceutil/trace.go:171","msg":"trace[2047735869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2155; }","duration":"110.260053ms","start":"2026-07-20T18:20:54.688169Z","end":"2026-07-20T18:20:54.798429Z","steps":["trace[2047735869] 'range keys from in-memory index tree' (duration: 110.076119ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:20:54.798413Z","caller":"traceutil/trace.go:171","msg":"trace[1969726919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2155; }","duration":"112.061727ms","start":"2026-07-20T18:20:54.686328Z","end":"2026-07-20T18:20:54.798389Z","steps":["trace[1969726919] 'range keys from in-memory index tree' (duration: 111.597505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:20:54.7982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.613222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-20T18:20:54.798997Z","caller":"traceutil/trace.go:171","msg":"trace[1122040586] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2155; }","duration":"111.449622ms","start":"2026-07-20T18:20:54.687519Z","end":"2026-07-20T18:20:54.798969Z","steps":["trace[1122040586] 'range keys from in-memory index tree' (duration: 110.380616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:20:54.798213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.601982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-20T18:20:54.799074Z","caller":"traceutil/trace.go:171","msg":"trace[10129396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2155; }","duration":"111.492913ms","start":"2026-07-20T18:20:54.687562Z","end":"2026-07-20T18:20:54.799055Z","steps":["trace[10129396] 'range keys from in-memory index tree' (duration: 110.460838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:20:55.110264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.88152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:20:55.110554Z","caller":"traceutil/trace.go:171","msg":"trace[2034514616] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"307.407069ms","start":"2026-07-20T18:20:54.803125Z","end":"2026-07-20T18:20:55.110532Z","steps":["trace[2034514616] 'process raft request' (duration: 307.330887ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:20:55.110539Z","caller":"traceutil/trace.go:171","msg":"trace[967776501] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"307.801979ms","start":"2026-07-20T18:20:54.802701Z","end":"2026-07-20T18:20:55.110503Z","steps":["trace[967776501] 'process raft request' (duration: 115.536919ms)","trace[967776501] 'compare' (duration: 191.537422ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:20:55.110634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:20:54.80312Z","time spent":"307.480161ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:20:55.110703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:20:54.802659Z","time spent":"307.950822ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:20:55.110763Z","caller":"traceutil/trace.go:171","msg":"trace[1603749434] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"307.658236ms","start":"2026-07-20T18:20:54.803071Z","end":"2026-07-20T18:20:55.110729Z","steps":["trace[1603749434] 'process raft request' (duration: 307.300887ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:20:55.110849Z","caller":"traceutil/trace.go:171","msg":"trace[67130423] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"307.599804ms","start":"2026-07-20T18:20:54.803236Z","end":"2026-07-20T18:20:55.110836Z","steps":["trace[67130423] 'process raft request' (duration: 307.250806ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:20:55.110801Z","caller":"traceutil/trace.go:171","msg":"trace[266446622] linearizableReadLoop","detail":"{readStateIndex:2250; appliedIndex:2246; }","duration":"255.983427ms","start":"2026-07-20T18:20:54.854775Z","end":"2026-07-20T18:20:55.110759Z","steps":["trace[266446622] 'read index received' (duration: 5.306877ms)","trace[266446622] 'applied index is now lower than readState.Index' (duration: 250.67327ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:20:55.110904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:20:54.803231Z","time spent":"307.645365ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:20:55.110914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:20:54.803063Z","time spent":"307.772228ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:20:55.112375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.289644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-20T18:20:55.112454Z","caller":"traceutil/trace.go:171","msg":"trace[2072482530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2160; }","duration":"257.708348ms","start":"2026-07-20T18:20:54.854726Z","end":"2026-07-20T18:20:55.112435Z","steps":["trace[2072482530] 'agreement among raft nodes before linearized reading' (duration: 256.167721ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:20:56.704592Z","caller":"traceutil/trace.go:171","msg":"trace[1406396588] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"139.035143ms","start":"2026-07-20T18:20:56.565534Z","end":"2026-07-20T18:20:56.70457Z","steps":["trace[1406396588] 'process raft request' (duration: 138.89339ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:20:56.704596Z","caller":"traceutil/trace.go:171","msg":"trace[510204921] linearizableReadLoop","detail":"{readStateIndex:2252; appliedIndex:2252; }","duration":"105.858138ms","start":"2026-07-20T18:20:56.598715Z","end":"2026-07-20T18:20:56.704574Z","steps":["trace[510204921] 'read index received' (duration: 105.849147ms)","trace[510204921] 'applied index is now lower than readState.Index' (duration: 7.571µs)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:20:56.705065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.289668ms","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:508"} {"level":"info","ts":"2026-07-20T18:20:56.705138Z","caller":"traceutil/trace.go:171","msg":"trace[1346601388] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2162; }","duration":"106.444192ms","start":"2026-07-20T18:20:56.598675Z","end":"2026-07-20T18:20:56.705119Z","steps":["trace[1346601388] 'agreement among raft nodes before linearized reading' (duration: 105.96383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:20:56.709784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.55148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-20T18:20:56.709885Z","caller":"traceutil/trace.go:171","msg":"trace[1578657660] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2163; }","duration":"110.708304ms","start":"2026-07-20T18:20:56.599153Z","end":"2026-07-20T18:20:56.709861Z","steps":["trace[1578657660] 'agreement among raft nodes before linearized reading' (duration: 110.419257ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:20:57.253799Z","caller":"traceutil/trace.go:171","msg":"trace[521842792] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"134.88552ms","start":"2026-07-20T18:20:57.118881Z","end":"2026-07-20T18:20:57.253767Z","steps":["trace[521842792] 'process raft request' (duration: 77.878865ms)","trace[521842792] 'compare' (duration: 56.685797ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:20:57.254042Z","caller":"traceutil/trace.go:171","msg":"trace[1620319907] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"134.796028ms","start":"2026-07-20T18:20:57.119228Z","end":"2026-07-20T18:20:57.254024Z","steps":["trace[1620319907] 'process raft request' (duration: 134.353638ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:20:57.255043Z","caller":"traceutil/trace.go:171","msg":"trace[103616947] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"134.555672ms","start":"2026-07-20T18:20:57.120468Z","end":"2026-07-20T18:20:57.255024Z","steps":["trace[103616947] 'process raft request' (duration: 133.21998ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:01.465098Z","caller":"traceutil/trace.go:171","msg":"trace[1731194634] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"146.510599ms","start":"2026-07-20T18:21:01.318557Z","end":"2026-07-20T18:21:01.465067Z","steps":["trace[1731194634] 'process raft request' (duration: 50.11654ms)","trace[1731194634] 'compare' (duration: 96.271176ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:21:01.475072Z","caller":"traceutil/trace.go:171","msg":"trace[595611411] linearizableReadLoop","detail":"{readStateIndex:2277; appliedIndex:2275; }","duration":"153.66688ms","start":"2026-07-20T18:21:01.321381Z","end":"2026-07-20T18:21:01.475048Z","steps":["trace[595611411] 'read index received' (duration: 47.282412ms)","trace[595611411] 'applied index is now lower than readState.Index' (duration: 106.383608ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:21:01.475136Z","caller":"traceutil/trace.go:171","msg":"trace[1149631283] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"156.207431ms","start":"2026-07-20T18:21:01.318893Z","end":"2026-07-20T18:21:01.4751Z","steps":["trace[1149631283] 'process raft request' (duration: 156.027297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:01.475269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.808224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-20T18:21:01.475286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.883696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-20T18:21:01.475319Z","caller":"traceutil/trace.go:171","msg":"trace[1847258282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2186; }","duration":"100.901306ms","start":"2026-07-20T18:21:01.374404Z","end":"2026-07-20T18:21:01.475305Z","steps":["trace[1847258282] 'agreement among raft nodes before linearized reading' (duration: 100.820194ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:01.475323Z","caller":"traceutil/trace.go:171","msg":"trace[1977510872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2186; }","duration":"153.960147ms","start":"2026-07-20T18:21:01.321353Z","end":"2026-07-20T18:21:01.475313Z","steps":["trace[1977510872] 'agreement among raft nodes before linearized reading' (duration: 153.818034ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:02.741296Z","caller":"traceutil/trace.go:171","msg":"trace[219557731] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"103.788596ms","start":"2026-07-20T18:21:02.637484Z","end":"2026-07-20T18:21:02.741272Z","steps":["trace[219557731] 'process raft request' (duration: 103.616352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:03.652935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.21563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:21:03.653043Z","caller":"traceutil/trace.go:171","msg":"trace[1731359025] linearizableReadLoop","detail":"{readStateIndex:2289; appliedIndex:2288; }","duration":"173.138405ms","start":"2026-07-20T18:21:03.479887Z","end":"2026-07-20T18:21:03.653026Z","steps":["trace[1731359025] 'read index received' (duration: 26.73841ms)","trace[1731359025] 'applied index is now lower than readState.Index' (duration: 146.398435ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:21:03.653075Z","caller":"traceutil/trace.go:171","msg":"trace[332186318] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"180.439121ms","start":"2026-07-20T18:21:03.472612Z","end":"2026-07-20T18:21:03.653051Z","steps":["trace[332186318] 'process raft request' (duration: 34.002965ms)","trace[332186318] 'compare' (duration: 146.030306ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:21:03.653399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.085961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-20T18:21:03.653448Z","caller":"traceutil/trace.go:171","msg":"trace[1526627868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2197; }","duration":"157.175473ms","start":"2026-07-20T18:21:03.496261Z","end":"2026-07-20T18:21:03.653436Z","steps":["trace[1526627868] 'agreement among raft nodes before linearized reading' (duration: 157.0468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:03.653843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.960725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-20T18:21:03.653899Z","caller":"traceutil/trace.go:171","msg":"trace[1816169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2197; }","duration":"174.055387ms","start":"2026-07-20T18:21:03.479833Z","end":"2026-07-20T18:21:03.653888Z","steps":["trace[1816169] 'agreement among raft nodes before linearized reading' (duration: 173.285488ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:03.865485Z","caller":"traceutil/trace.go:171","msg":"trace[772525725] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"208.510523ms","start":"2026-07-20T18:21:03.656949Z","end":"2026-07-20T18:21:03.865459Z","steps":["trace[772525725] 'process raft request' (duration: 208.356959ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:03.912216Z","caller":"traceutil/trace.go:171","msg":"trace[343804464] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"254.902314ms","start":"2026-07-20T18:21:03.657293Z","end":"2026-07-20T18:21:03.912195Z","steps":["trace[343804464] 'process raft request' (duration: 254.786091ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:05.405361Z","caller":"traceutil/trace.go:171","msg":"trace[2026990095] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"111.123441ms","start":"2026-07-20T18:21:05.294193Z","end":"2026-07-20T18:21:05.405317Z","steps":["trace[2026990095] 'process raft request' (duration: 110.934707ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:06.047547Z","caller":"traceutil/trace.go:171","msg":"trace[1246078676] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"128.359364ms","start":"2026-07-20T18:21:05.919162Z","end":"2026-07-20T18:21:06.047521Z","steps":["trace[1246078676] 'process raft request' (duration: 70.503038ms)","trace[1246078676] 'compare' (duration: 57.762804ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:21:08.151959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.65244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-20T18:21:08.152107Z","caller":"traceutil/trace.go:171","msg":"trace[1602888800] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2216; }","duration":"100.856555ms","start":"2026-07-20T18:21:08.051232Z","end":"2026-07-20T18:21:08.152089Z","steps":["trace[1602888800] 'range keys from in-memory index tree' (duration: 100.466076ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:09.450193Z","caller":"traceutil/trace.go:171","msg":"trace[710433473] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"306.678714ms","start":"2026-07-20T18:21:09.143473Z","end":"2026-07-20T18:21:09.450151Z","steps":["trace[710433473] 'process raft request' (duration: 241.407951ms)","trace[710433473] 'compare' (duration: 64.957696ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:21:09.450318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:21:09.143451Z","time spent":"306.802187ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:21:09.675127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.60096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-20T18:21:09.67522Z","caller":"traceutil/trace.go:171","msg":"trace[895847220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2221; }","duration":"123.750723ms","start":"2026-07-20T18:21:09.551447Z","end":"2026-07-20T18:21:09.675198Z","steps":["trace[895847220] 'range keys from in-memory index tree' (duration: 123.392304ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:10.312285Z","caller":"traceutil/trace.go:171","msg":"trace[1552593686] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"100.916606ms","start":"2026-07-20T18:21:10.211344Z","end":"2026-07-20T18:21:10.31226Z","steps":["trace[1552593686] 'process raft request' (duration: 100.775933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:11.766599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.862545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:21:11.766761Z","caller":"traceutil/trace.go:171","msg":"trace[1031451393] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"214.795813ms","start":"2026-07-20T18:21:11.551913Z","end":"2026-07-20T18:21:11.766708Z","steps":["trace[1031451393] 'process raft request' (duration: 103.761044ms)","trace[1031451393] 'compare' (duration: 110.601559ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:21:11.972383Z","caller":"traceutil/trace.go:171","msg":"trace[554740064] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"200.555703ms","start":"2026-07-20T18:21:11.771791Z","end":"2026-07-20T18:21:11.972347Z","steps":["trace[554740064] 'process raft request' (duration: 200.287796ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:11.97259Z","caller":"traceutil/trace.go:171","msg":"trace[787441700] linearizableReadLoop","detail":"{readStateIndex:2325; appliedIndex:2323; }","duration":"128.21489ms","start":"2026-07-20T18:21:11.84434Z","end":"2026-07-20T18:21:11.972555Z","steps":["trace[787441700] 'read index received' (duration: 127.638876ms)","trace[787441700] 'applied index is now lower than readState.Index' (duration: 561.843µs)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:21:11.972614Z","caller":"traceutil/trace.go:171","msg":"trace[622842235] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"197.476339ms","start":"2026-07-20T18:21:11.775115Z","end":"2026-07-20T18:21:11.972592Z","steps":["trace[622842235] 'process raft request' (duration: 197.180722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:11.972895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.781773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-20T18:21:11.972961Z","caller":"traceutil/trace.go:171","msg":"trace[1292102424] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2232; }","duration":"110.891086ms","start":"2026-07-20T18:21:11.862053Z","end":"2026-07-20T18:21:11.972944Z","steps":["trace[1292102424] 'agreement among raft nodes before linearized reading' (duration: 110.708042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:11.972962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.61008ms","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:508"} {"level":"info","ts":"2026-07-20T18:21:11.973007Z","caller":"traceutil/trace.go:171","msg":"trace[92780463] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2232; }","duration":"128.708242ms","start":"2026-07-20T18:21:11.844286Z","end":"2026-07-20T18:21:11.972995Z","steps":["trace[92780463] 'agreement among raft nodes before linearized reading' (duration: 128.377244ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:12.197139Z","caller":"traceutil/trace.go:171","msg":"trace[1546128884] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"219.983778ms","start":"2026-07-20T18:21:11.977129Z","end":"2026-07-20T18:21:12.197113Z","steps":["trace[1546128884] 'process raft request' (duration: 127.091974ms)","trace[1546128884] 'compare' (duration: 92.647158ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:21:12.197172Z","caller":"traceutil/trace.go:171","msg":"trace[702147929] linearizableReadLoop","detail":"{readStateIndex:2327; appliedIndex:2325; }","duration":"171.000735ms","start":"2026-07-20T18:21:12.026059Z","end":"2026-07-20T18:21:12.197059Z","steps":["trace[702147929] 'read index received' (duration: 78.143031ms)","trace[702147929] 'applied index is now lower than readState.Index' (duration: 92.856634ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:21:12.197169Z","caller":"traceutil/trace.go:171","msg":"trace[1294847922] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"219.416944ms","start":"2026-07-20T18:21:11.977722Z","end":"2026-07-20T18:21:12.197139Z","steps":["trace[1294847922] 'process raft request' (duration: 219.283111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:12.197355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.282323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-20T18:21:12.197502Z","caller":"traceutil/trace.go:171","msg":"trace[1568777065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2234; }","duration":"171.460806ms","start":"2026-07-20T18:21:12.02601Z","end":"2026-07-20T18:21:12.19747Z","steps":["trace[1568777065] 'agreement among raft nodes before linearized reading' (duration: 171.21191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:12.696778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.759771ms","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-07-20T18:21:12.696871Z","caller":"traceutil/trace.go:171","msg":"trace[1956847203] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:2236; }","duration":"102.893864ms","start":"2026-07-20T18:21:12.593955Z","end":"2026-07-20T18:21:12.696848Z","steps":["trace[1956847203] 'range keys from in-memory index tree' (duration: 102.559556ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:12.959324Z","caller":"traceutil/trace.go:171","msg":"trace[1823306681] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"173.991776ms","start":"2026-07-20T18:21:12.785303Z","end":"2026-07-20T18:21:12.959295Z","steps":["trace[1823306681] 'process raft request' (duration: 97.29174ms)","trace[1823306681] 'compare' (duration: 76.555323ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:21:13.15472Z","caller":"traceutil/trace.go:171","msg":"trace[2130845663] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"312.139585ms","start":"2026-07-20T18:21:12.842557Z","end":"2026-07-20T18:21:13.154697Z","steps":["trace[2130845663] 'process raft request' (duration: 253.767537ms)","trace[2130845663] 'compare' (duration: 58.223334ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:21:13.154841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:21:12.842532Z","time spent":"312.246598ms","remote":"127.0.0.1:41774","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-07-20T18:21:13.154984Z","caller":"traceutil/trace.go:171","msg":"trace[1595818776] linearizableReadLoop","detail":"{readStateIndex:2333; appliedIndex:2331; }","duration":"193.918774ms","start":"2026-07-20T18:21:12.961008Z","end":"2026-07-20T18:21:13.154927Z","steps":["trace[1595818776] 'read index received' (duration: 135.466494ms)","trace[1595818776] 'applied index is now lower than readState.Index' (duration: 58.45004ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:21:13.155089Z","caller":"traceutil/trace.go:171","msg":"trace[1895534794] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"270.445126ms","start":"2026-07-20T18:21:12.884619Z","end":"2026-07-20T18:21:13.155064Z","steps":["trace[1895534794] 'process raft request' (duration: 270.042697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:13.155154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.122709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-20T18:21:13.15521Z","caller":"traceutil/trace.go:171","msg":"trace[1972208610] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2239; }","duration":"194.238542ms","start":"2026-07-20T18:21:12.960954Z","end":"2026-07-20T18:21:13.155192Z","steps":["trace[1972208610] 'agreement among raft nodes before linearized reading' (duration: 194.114369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:14.501246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.427722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:21:14.501317Z","caller":"traceutil/trace.go:171","msg":"trace[412197277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2245; }","duration":"127.590075ms","start":"2026-07-20T18:21:14.373709Z","end":"2026-07-20T18:21:14.501299Z","steps":["trace[412197277] 'range keys from in-memory index tree' (duration: 127.338549ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:15.928065Z","caller":"traceutil/trace.go:171","msg":"trace[457795703] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"106.416888ms","start":"2026-07-20T18:21:15.82163Z","end":"2026-07-20T18:21:15.928047Z","steps":["trace[457795703] 'process raft request' (duration: 106.294385ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:18.077342Z","caller":"traceutil/trace.go:171","msg":"trace[1041567377] linearizableReadLoop","detail":"{readStateIndex:2353; appliedIndex:2352; }","duration":"145.628546ms","start":"2026-07-20T18:21:17.931686Z","end":"2026-07-20T18:21:18.077315Z","steps":["trace[1041567377] 'read index received' (duration: 50.894608ms)","trace[1041567377] 'applied index is now lower than readState.Index' (duration: 94.732278ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:21:18.077565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.859622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-20T18:21:18.077604Z","caller":"traceutil/trace.go:171","msg":"trace[1623197127] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2258; }","duration":"145.959935ms","start":"2026-07-20T18:21:17.931633Z","end":"2026-07-20T18:21:18.077593Z","steps":["trace[1623197127] 'agreement among raft nodes before linearized reading' (duration: 145.783941ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:18.32638Z","caller":"traceutil/trace.go:171","msg":"trace[1375869546] transaction","detail":"{read_only:false; response_revision:2260; number_of_response:1; }","duration":"171.463476ms","start":"2026-07-20T18:21:18.154899Z","end":"2026-07-20T18:21:18.326362Z","steps":["trace[1375869546] 'process raft request' (duration: 171.351353ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:18.377755Z","caller":"traceutil/trace.go:171","msg":"trace[753785307] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"222.094289ms","start":"2026-07-20T18:21:18.155618Z","end":"2026-07-20T18:21:18.377712Z","steps":["trace[753785307] 'process raft request' (duration: 221.893374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:18.656625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.80376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:21:18.656794Z","caller":"traceutil/trace.go:171","msg":"trace[1772977924] linearizableReadLoop","detail":"{readStateIndex:2357; appliedIndex:2356; }","duration":"221.435743ms","start":"2026-07-20T18:21:18.435333Z","end":"2026-07-20T18:21:18.656769Z","steps":["trace[1772977924] 'read index received' (duration: 48.821µs)","trace[1772977924] 'applied index is now lower than readState.Index' (duration: 221.385492ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:21:18.657005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.636578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:21:18.657035Z","caller":"traceutil/trace.go:171","msg":"trace[1616574429] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"275.816215ms","start":"2026-07-20T18:21:18.381189Z","end":"2026-07-20T18:21:18.657006Z","steps":["trace[1616574429] 'process raft request' (duration: 23.489233ms)","trace[1616574429] 'compare' (duration: 251.631686ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:21:18.657096Z","caller":"traceutil/trace.go:171","msg":"trace[2053171967] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2262; }","duration":"221.813762ms","start":"2026-07-20T18:21:18.435263Z","end":"2026-07-20T18:21:18.657077Z","steps":["trace[2053171967] 'agreement among raft nodes before linearized reading' (duration: 221.621077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:19.351373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.423831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:21:19.351466Z","caller":"traceutil/trace.go:171","msg":"trace[319043890] linearizableReadLoop","detail":"{readStateIndex:2358; appliedIndex:2357; }","duration":"489.356489ms","start":"2026-07-20T18:21:18.862094Z","end":"2026-07-20T18:21:19.35145Z","steps":["trace[319043890] 'read index received' (duration: 361.769954ms)","trace[319043890] 'applied index is now lower than readState.Index' (duration: 127.585045ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:21:19.351731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.623615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-20T18:21:19.351747Z","caller":"traceutil/trace.go:171","msg":"trace[1150214240] transaction","detail":"{read_only:false; response_revision:2263; number_of_response:1; }","duration":"690.725331ms","start":"2026-07-20T18:21:18.660932Z","end":"2026-07-20T18:21:19.351658Z","steps":["trace[1150214240] 'process raft request' (duration: 562.945001ms)","trace[1150214240] 'compare' (duration: 126.961861ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:21:19.351784Z","caller":"traceutil/trace.go:171","msg":"trace[776266138] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2263; }","duration":"489.712417ms","start":"2026-07-20T18:21:18.862058Z","end":"2026-07-20T18:21:19.351771Z","steps":["trace[776266138] 'agreement among raft nodes before linearized reading' (duration: 489.590684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:19.351821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:21:18.862039Z","time spent":"489.772229ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-07-20T18:21:19.351957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:21:18.660912Z","time spent":"690.871324ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:21:19.353861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.905692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-07-20T18:21:19.35395Z","caller":"traceutil/trace.go:171","msg":"trace[656036148] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2264; }","duration":"105.038135ms","start":"2026-07-20T18:21:19.248888Z","end":"2026-07-20T18:21:19.353926Z","steps":["trace[656036148] 'agreement among raft nodes before linearized reading' (duration: 104.722628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:21.677693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.478062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T18:21:21.67782Z","caller":"traceutil/trace.go:171","msg":"trace[1259379442] transaction","detail":"{read_only:false; response_revision:2273; number_of_response:1; }","duration":"318.908316ms","start":"2026-07-20T18:21:21.358886Z","end":"2026-07-20T18:21:21.677794Z","steps":["trace[1259379442] 'process raft request' (duration: 148.24458ms)","trace[1259379442] 'compare' (duration: 170.239286ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T18:21:21.677881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:21:21.358865Z","time spent":"318.984558ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T18:21:22.04648Z","caller":"traceutil/trace.go:171","msg":"trace[511208157] transaction","detail":"{read_only:false; response_revision:2275; number_of_response:1; }","duration":"161.786844ms","start":"2026-07-20T18:21:21.884669Z","end":"2026-07-20T18:21:22.046456Z","steps":["trace[511208157] 'process raft request' (duration: 161.653551ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:22.511281Z","caller":"traceutil/trace.go:171","msg":"trace[368008113] linearizableReadLoop","detail":"{readStateIndex:2371; appliedIndex:2370; }","duration":"207.031308ms","start":"2026-07-20T18:21:22.304226Z","end":"2026-07-20T18:21:22.511258Z","steps":["trace[368008113] 'read index received' (duration: 206.854233ms)","trace[368008113] 'applied index is now lower than readState.Index' (duration: 176.065µs)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:21:22.511398Z","caller":"traceutil/trace.go:171","msg":"trace[209359031] transaction","detail":"{read_only:false; response_revision:2276; number_of_response:1; }","duration":"219.518067ms","start":"2026-07-20T18:21:22.291849Z","end":"2026-07-20T18:21:22.511367Z","steps":["trace[209359031] 'process raft request' (duration: 219.252721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:22.511513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.274193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-20T18:21:22.511557Z","caller":"traceutil/trace.go:171","msg":"trace[890408030] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2276; }","duration":"207.369355ms","start":"2026-07-20T18:21:22.304175Z","end":"2026-07-20T18:21:22.511544Z","steps":["trace[890408030] 'agreement among raft nodes before linearized reading' (duration: 207.220772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:22.511594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.722537ms","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:507"} {"level":"info","ts":"2026-07-20T18:21:22.511681Z","caller":"traceutil/trace.go:171","msg":"trace[45046684] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2276; }","duration":"205.842549ms","start":"2026-07-20T18:21:22.30581Z","end":"2026-07-20T18:21:22.511653Z","steps":["trace[45046684] 'agreement among raft nodes before linearized reading' (duration: 205.610693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:22.511693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.852197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T18:21:22.511721Z","caller":"traceutil/trace.go:171","msg":"trace[1730244451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2276; }","duration":"136.892728ms","start":"2026-07-20T18:21:22.374819Z","end":"2026-07-20T18:21:22.511712Z","steps":["trace[1730244451] 'agreement among raft nodes before linearized reading' (duration: 136.851507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:22.511864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.085788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-20T18:21:22.511893Z","caller":"traceutil/trace.go:171","msg":"trace[624861319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2276; }","duration":"146.188631ms","start":"2026-07-20T18:21:22.365695Z","end":"2026-07-20T18:21:22.511883Z","steps":["trace[624861319] 'agreement among raft nodes before linearized reading' (duration: 146.109279ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:22.677221Z","caller":"traceutil/trace.go:171","msg":"trace[1685311134] transaction","detail":"{read_only:false; response_revision:2278; number_of_response:1; }","duration":"160.868622ms","start":"2026-07-20T18:21:22.516322Z","end":"2026-07-20T18:21:22.677191Z","steps":["trace[1685311134] 'process raft request' (duration: 160.462602ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:22.67721Z","caller":"traceutil/trace.go:171","msg":"trace[1097283978] transaction","detail":"{read_only:false; response_revision:2279; number_of_response:1; }","duration":"160.629587ms","start":"2026-07-20T18:21:22.516548Z","end":"2026-07-20T18:21:22.677178Z","steps":["trace[1097283978] 'process raft request' (duration: 160.277028ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:22.677508Z","caller":"traceutil/trace.go:171","msg":"trace[550667176] transaction","detail":"{read_only:false; response_revision:2277; number_of_response:1; }","duration":"161.62298ms","start":"2026-07-20T18:21:22.515872Z","end":"2026-07-20T18:21:22.677495Z","steps":["trace[550667176] 'process raft request' (duration: 115.68088ms)","trace[550667176] 'compare' (duration: 45.115941ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:21:22.677819Z","caller":"traceutil/trace.go:171","msg":"trace[100494841] transaction","detail":"{read_only:false; response_revision:2280; number_of_response:1; }","duration":"161.216461ms","start":"2026-07-20T18:21:22.516588Z","end":"2026-07-20T18:21:22.677805Z","steps":["trace[100494841] 'process raft request' (duration: 160.269428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:21:23.814848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.586379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-20T18:21:23.814911Z","caller":"traceutil/trace.go:171","msg":"trace[1608617180] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2284; }","duration":"133.714592ms","start":"2026-07-20T18:21:23.681182Z","end":"2026-07-20T18:21:23.814897Z","steps":["trace[1608617180] 'range keys from in-memory index tree' (duration: 133.429096ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:21:24.894868Z","caller":"traceutil/trace.go:171","msg":"trace[1008654345] transaction","detail":"{read_only:false; response_revision:2291; number_of_response:1; }","duration":"121.642692ms","start":"2026-07-20T18:21:24.773201Z","end":"2026-07-20T18:21:24.894844Z","steps":["trace[1008654345] 'process raft request' (duration: 42.58659ms)","trace[1008654345] 'compare' (duration: 78.9389ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:22:31.620314Z","caller":"traceutil/trace.go:171","msg":"trace[446771292] linearizableReadLoop","detail":"{readStateIndex:2728; appliedIndex:2727; }","duration":"405.107043ms","start":"2026-07-20T18:22:31.215179Z","end":"2026-07-20T18:22:31.620286Z","steps":["trace[446771292] 'read index received' (duration: 404.757204ms)","trace[446771292] 'applied index is now lower than readState.Index' (duration: 348.789µs)"],"step_count":2} {"level":"info","ts":"2026-07-20T18:22:31.620441Z","caller":"traceutil/trace.go:171","msg":"trace[55660508] transaction","detail":"{read_only:false; response_revision:2618; number_of_response:1; }","duration":"459.113915ms","start":"2026-07-20T18:22:31.161314Z","end":"2026-07-20T18:22:31.620428Z","steps":["trace[55660508] 'process raft request' (duration: 458.710216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:22:31.620547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:22:31.161301Z","time spent":"459.169486ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T18:22:31.620906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.159006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-20T18:22:31.620968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.941349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-20T18:22:31.621007Z","caller":"traceutil/trace.go:171","msg":"trace[1973431137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2618; }","duration":"245.311159ms","start":"2026-07-20T18:22:31.375673Z","end":"2026-07-20T18:22:31.620984Z","steps":["trace[1973431137] 'agreement among raft nodes before linearized reading' (duration: 245.113245ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T18:22:31.621122Z","caller":"traceutil/trace.go:171","msg":"trace[1466954574] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2618; }","duration":"219.124252ms","start":"2026-07-20T18:22:31.401936Z","end":"2026-07-20T18:22:31.62106Z","steps":["trace[1466954574] 'agreement among raft nodes before linearized reading' (duration: 218.776034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:22:31.620873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.395313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-20T18:22:31.621223Z","caller":"traceutil/trace.go:171","msg":"trace[1313618554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2618; }","duration":"388.762412ms","start":"2026-07-20T18:22:31.232414Z","end":"2026-07-20T18:22:31.621176Z","steps":["trace[1313618554] 'agreement among raft nodes before linearized reading' (duration: 388.26536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:22:31.62128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:22:31.232345Z","time spent":"388.921655ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-20T18:22:31.621389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.189489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-20T18:22:31.621477Z","caller":"traceutil/trace.go:171","msg":"trace[291114061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2618; }","duration":"406.341933ms","start":"2026-07-20T18:22:31.215112Z","end":"2026-07-20T18:22:31.621454Z","steps":["trace[291114061] 'agreement among raft nodes before linearized reading' (duration: 406.035075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T18:22:31.621531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T18:22:31.215086Z","time spent":"406.435914ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}