{"level":"warn","ts":"2026-04-27T19:43:40.891857Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T19:43:40.891955Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.191:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.191:2380","--initial-cluster=instance=https://199.19.213.191:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.191:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.191:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-27T19:43:40.892021Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T19:43:40.892034Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.191:2380"]} {"level":"info","ts":"2026-04-27T19:43:40.892049Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-27T19:43:40.892685Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.191:2379"]} {"level":"info","ts":"2026-04-27T19:43:40.892765Z","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.19.213.191:2380"],"listen-peer-urls":["https://199.19.213.191:2380"],"advertise-client-urls":["https://199.19.213.191:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.191:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.191:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-27T19:43:41.092376Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"199.325199ms"} {"level":"info","ts":"2026-04-27T19:43:41.220684Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e892d50a3a4fc0f4","cluster-id":"21eccdda20ff4e92"} {"level":"info","ts":"2026-04-27T19:43:41.220907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T19:43:41.22105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became follower at term 0"} {"level":"info","ts":"2026-04-27T19:43:41.221173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e892d50a3a4fc0f4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T19:43:41.221266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became follower at term 1"} {"level":"info","ts":"2026-04-27T19:43:41.2214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 switched to configuration voters=(16758691403303731444)"} {"level":"warn","ts":"2026-04-27T19:43:41.230717Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T19:43:41.232354Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T19:43:41.23425Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-27T19:43:41.237532Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e892d50a3a4fc0f4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T19:43:41.23795Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e892d50a3a4fc0f4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T19:43:41.238067Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-27T19:43:41.238132Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-27T19:43:41.238158Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-27T19:43:41.239758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 switched to configuration voters=(16758691403303731444)"} {"level":"info","ts":"2026-04-27T19:43:41.240149Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"21eccdda20ff4e92","local-member-id":"e892d50a3a4fc0f4","added-peer-id":"e892d50a3a4fc0f4","added-peer-peer-urls":["https://199.19.213.191:2380"]} {"level":"info","ts":"2026-04-27T19:43:41.243568Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-27T19:43:41.243818Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e892d50a3a4fc0f4","initial-advertise-peer-urls":["https://199.19.213.191:2380"],"listen-peer-urls":["https://199.19.213.191:2380"],"advertise-client-urls":["https://199.19.213.191:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.191:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T19:43:41.24387Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T19:43:41.244708Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.191:2380"} {"level":"info","ts":"2026-04-27T19:43:41.244749Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.191:2380"} {"level":"info","ts":"2026-04-27T19:43:41.824629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T19:43:41.824698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T19:43:41.824746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 received MsgPreVoteResp from e892d50a3a4fc0f4 at term 1"} {"level":"info","ts":"2026-04-27T19:43:41.824773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became candidate at term 2"} {"level":"info","ts":"2026-04-27T19:43:41.82479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 received MsgVoteResp from e892d50a3a4fc0f4 at term 2"} {"level":"info","ts":"2026-04-27T19:43:41.824811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became leader at term 2"} {"level":"info","ts":"2026-04-27T19:43:41.824831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e892d50a3a4fc0f4 elected leader e892d50a3a4fc0f4 at term 2"} {"level":"info","ts":"2026-04-27T19:43:41.832401Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T19:43:41.832804Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e892d50a3a4fc0f4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.191:2379]}","request-path":"/0/members/e892d50a3a4fc0f4/attributes","cluster-id":"21eccdda20ff4e92","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T19:43:41.832835Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T19:43:41.832808Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T19:43:41.833081Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T19:43:41.833103Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T19:43:41.834515Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"21eccdda20ff4e92","local-member-id":"e892d50a3a4fc0f4","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T19:43:41.834649Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T19:43:41.834678Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T19:43:41.835423Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T19:43:41.836619Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.191:2379"} {"level":"warn","ts":"2026-04-27T19:43:47.20529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.623758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:43:47.205499Z","caller":"traceutil/trace.go:171","msg":"trace[1895847423] linearizableReadLoop","detail":"{readStateIndex:242; appliedIndex:240; }","duration":"577.457827ms","start":"2026-04-27T19:43:46.628026Z","end":"2026-04-27T19:43:47.205484Z","steps":["trace[1895847423] 'read index received' (duration: 29.711µs)","trace[1895847423] 'applied index is now lower than readState.Index' (duration: 577.427006ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:43:47.205529Z","caller":"traceutil/trace.go:171","msg":"trace[1420810124] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"980.871759ms","start":"2026-04-27T19:43:46.224632Z","end":"2026-04-27T19:43:47.205503Z","steps":["trace[1420810124] 'process raft request' (duration: 215.501993ms)","trace[1420810124] 'compare' (duration: 764.373251ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:43:47.205554Z","caller":"traceutil/trace.go:171","msg":"trace[125262079] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"954.391755ms","start":"2026-04-27T19:43:46.25114Z","end":"2026-04-27T19:43:47.205532Z","steps":["trace[125262079] 'process raft request' (duration: 954.277082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:47.205587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.56076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:43:47.205615Z","caller":"traceutil/trace.go:171","msg":"trace[1068663644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:236; }","duration":"577.628641ms","start":"2026-04-27T19:43:46.627978Z","end":"2026-04-27T19:43:47.205606Z","steps":["trace[1068663644] 'agreement among raft nodes before linearized reading' (duration: 577.56179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:47.205627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:46.224617Z","time spent":"980.964391ms","remote":"127.0.0.1:32962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:43:47.20563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:46.251095Z","time spent":"954.502307ms","remote":"127.0.0.1:33076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":356,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:43:47.205659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:46.627958Z","time spent":"577.690643ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T19:43:47.712002Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903911468292286784,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:43:48.213175Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903911468292286784,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:43:48.71401Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903911468292286784,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:43:48.955854Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.338285507s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T19:43:48.970032Z","caller":"traceutil/trace.go:171","msg":"trace[2020533927] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"1.760438827s","start":"2026-04-27T19:43:47.20955Z","end":"2026-04-27T19:43:48.969989Z","steps":["trace[2020533927] 'process raft request' (duration: 1.746614524s)","trace[2020533927] 'compare' (duration: 13.355021ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:43:48.970912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:47.209527Z","time spent":"1.76132422s","remote":"127.0.0.1:33080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":481,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:43:49.335411Z","caller":"traceutil/trace.go:171","msg":"trace[1615971034] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"2.125549804s","start":"2026-04-27T19:43:47.209841Z","end":"2026-04-27T19:43:49.335391Z","steps":["trace[1615971034] 'process raft request' (duration: 2.115487448s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:49.33553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:47.209821Z","time spent":"2.125666647s","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:43:49.335535Z","caller":"traceutil/trace.go:171","msg":"trace[462889040] linearizableReadLoop","detail":"{readStateIndex:244; appliedIndex:242; }","duration":"2.124100517s","start":"2026-04-27T19:43:47.211361Z","end":"2026-04-27T19:43:49.335461Z","steps":["trace[462889040] 'read index received' (duration: 1.74489276s)","trace[462889040] 'applied index is now lower than readState.Index' (duration: 379.190406ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:43:49.335732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.079364347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:43:49.335728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.124347493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-27T19:43:49.335765Z","caller":"traceutil/trace.go:171","msg":"trace[1708450750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:239; }","duration":"2.079452759s","start":"2026-04-27T19:43:47.256302Z","end":"2026-04-27T19:43:49.335754Z","steps":["trace[1708450750] 'agreement among raft nodes before linearized reading' (duration: 2.079355647s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:49.335764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.036973476s","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-04-27T19:43:49.335783Z","caller":"traceutil/trace.go:171","msg":"trace[420947720] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:239; }","duration":"2.124429724s","start":"2026-04-27T19:43:47.211339Z","end":"2026-04-27T19:43:49.335769Z","steps":["trace[420947720] 'agreement among raft nodes before linearized reading' (duration: 2.12423641s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:49.335776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.052425279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-27T19:43:49.3358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:47.256285Z","time spent":"2.07950741s","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T19:43:49.335808Z","caller":"traceutil/trace.go:171","msg":"trace[1177232258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:239; }","duration":"2.037044568s","start":"2026-04-27T19:43:47.298749Z","end":"2026-04-27T19:43:49.335793Z","steps":["trace[1177232258] 'agreement among raft nodes before linearized reading' (duration: 2.036969396s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:49.335814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:47.211333Z","time spent":"2.124475676s","remote":"127.0.0.1:32862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-27T19:43:49.335827Z","caller":"traceutil/trace.go:171","msg":"trace[279323438] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:239; }","duration":"2.052500982s","start":"2026-04-27T19:43:47.283313Z","end":"2026-04-27T19:43:49.335814Z","steps":["trace[279323438] 'agreement among raft nodes before linearized reading' (duration: 2.052416449s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:43:49.335842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:47.298737Z","time spent":"2.037095589s","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T19:43:49.335862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:43:47.2833Z","time spent":"2.052554153s","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T19:44:01.57701Z","caller":"traceutil/trace.go:171","msg":"trace[1919520676] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"120.05692ms","start":"2026-04-27T19:44:01.456917Z","end":"2026-04-27T19:44:01.576974Z","steps":["trace[1919520676] 'process raft request' (duration: 75.975686ms)","trace[1919520676] 'compare' (duration: 43.897689ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:44:01.577348Z","caller":"traceutil/trace.go:171","msg":"trace[181022740] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"120.037229ms","start":"2026-04-27T19:44:01.457295Z","end":"2026-04-27T19:44:01.577332Z","steps":["trace[181022740] 'process raft request' (duration: 119.953657ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:01.577354Z","caller":"traceutil/trace.go:171","msg":"trace[155098552] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"120.262325ms","start":"2026-04-27T19:44:01.457062Z","end":"2026-04-27T19:44:01.577324Z","steps":["trace[155098552] 'process raft request' (duration: 119.868975ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:12.919709Z","caller":"traceutil/trace.go:171","msg":"trace[834681355] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"147.353081ms","start":"2026-04-27T19:44:12.772336Z","end":"2026-04-27T19:44:12.919689Z","steps":["trace[834681355] 'process raft request' (duration: 147.135526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:13.186193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.014428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:44:13.186316Z","caller":"traceutil/trace.go:171","msg":"trace[1341662489] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"242.017707ms","start":"2026-04-27T19:44:12.944285Z","end":"2026-04-27T19:44:13.186303Z","steps":["trace[1341662489] 'process raft request' (duration: 136.843475ms)","trace[1341662489] 'compare' (duration: 104.882295ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:44:15.030186Z","caller":"traceutil/trace.go:171","msg":"trace[1976075716] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"227.218093ms","start":"2026-04-27T19:44:14.802947Z","end":"2026-04-27T19:44:15.030165Z","steps":["trace[1976075716] 'process raft request' (duration: 227.058359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:15.550701Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903911468292287323,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:44:16.05148Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903911468292287323,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:44:16.111849Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.080711765s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T19:44:16.112472Z","caller":"traceutil/trace.go:171","msg":"trace[1536467155] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.304746888s","start":"2026-04-27T19:44:14.807693Z","end":"2026-04-27T19:44:16.11244Z","steps":["trace[1536467155] 'process raft request' (duration: 1.304544203s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:16.112537Z","caller":"traceutil/trace.go:171","msg":"trace[166894871] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.298560882s","start":"2026-04-27T19:44:14.813962Z","end":"2026-04-27T19:44:16.112523Z","steps":["trace[166894871] 'process raft request' (duration: 1.298415518s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:16.112602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:14.813949Z","time spent":"1.298621254s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:44:16.112602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:14.807675Z","time spent":"1.304866991s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:44:16.112691Z","caller":"traceutil/trace.go:171","msg":"trace[981220569] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.296429418s","start":"2026-04-27T19:44:14.816254Z","end":"2026-04-27T19:44:16.112683Z","steps":["trace[981220569] 'process raft request' (duration: 1.296297154s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:16.112694Z","caller":"traceutil/trace.go:171","msg":"trace[2026961965] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.296889s","start":"2026-04-27T19:44:14.815797Z","end":"2026-04-27T19:44:16.112686Z","steps":["trace[2026961965] 'process raft request' (duration: 1.296692225s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:16.112711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:14.816246Z","time spent":"1.296455118s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:44:16.112724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:14.81579Z","time spent":"1.29691755s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:44:16.112785Z","caller":"traceutil/trace.go:171","msg":"trace[153896571] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.288837785s","start":"2026-04-27T19:44:14.823942Z","end":"2026-04-27T19:44:16.112779Z","steps":["trace[153896571] 'process raft request' (duration: 1.28865934s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:16.112739Z","caller":"traceutil/trace.go:171","msg":"trace[1583235427] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.296991691s","start":"2026-04-27T19:44:14.815687Z","end":"2026-04-27T19:44:16.112679Z","steps":["trace[1583235427] 'process raft request' (duration: 1.296754446s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:16.112813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:14.823923Z","time spent":"1.288876666s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:44:16.112824Z","caller":"traceutil/trace.go:171","msg":"trace[1128278061] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.28861965s","start":"2026-04-27T19:44:14.824171Z","end":"2026-04-27T19:44:16.11279Z","steps":["trace[1128278061] 'process raft request' (duration: 1.288473347s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:16.112943Z","caller":"traceutil/trace.go:171","msg":"trace[1446667843] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.265893205s","start":"2026-04-27T19:44:14.847042Z","end":"2026-04-27T19:44:16.112935Z","steps":["trace[1446667843] 'process raft request' (duration: 1.265654579s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:16.112976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:14.847025Z","time spent":"1.265934235s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:44:16.112966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:14.815664Z","time spent":"1.297194277s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:44:16.112972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:14.824136Z","time spent":"1.288764113s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:44:16.112998Z","caller":"traceutil/trace.go:171","msg":"trace[1018185176] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.231920605s","start":"2026-04-27T19:44:14.88105Z","end":"2026-04-27T19:44:16.11297Z","steps":["trace[1018185176] 'process raft request' (duration: 1.231704569s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:16.113083Z","caller":"traceutil/trace.go:171","msg":"trace[1611599834] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.216459752s","start":"2026-04-27T19:44:14.896609Z","end":"2026-04-27T19:44:16.113069Z","steps":["trace[1611599834] 'process raft request' (duration: 1.216295108s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:16.11312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:14.896576Z","time spent":"1.216527024s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:44:16.113111Z","caller":"traceutil/trace.go:171","msg":"trace[1983099754] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.120910143s","start":"2026-04-27T19:44:14.992156Z","end":"2026-04-27T19:44:16.113066Z","steps":["trace[1983099754] 'process raft request' (duration: 1.120833311s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:16.113221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:14.881022Z","time spent":"1.23215074s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:44:16.113265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:14.992131Z","time spent":"1.121032157s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:44:16.193944Z","caller":"traceutil/trace.go:171","msg":"trace[1257765428] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.157143051s","start":"2026-04-27T19:44:15.036777Z","end":"2026-04-27T19:44:16.19392Z","steps":["trace[1257765428] 'process raft request' (duration: 1.156939435s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:16.194009Z","caller":"traceutil/trace.go:171","msg":"trace[1128006666] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.157174391s","start":"2026-04-27T19:44:15.036814Z","end":"2026-04-27T19:44:16.193988Z","steps":["trace[1128006666] 'process raft request' (duration: 1.156999447s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:16.194152Z","caller":"traceutil/trace.go:171","msg":"trace[143684157] transaction","detail":"{read_only:false; number_of_response:1; response_revision:526; }","duration":"1.156633808s","start":"2026-04-27T19:44:15.037506Z","end":"2026-04-27T19:44:16.19414Z","steps":["trace[143684157] 'process raft request' (duration: 1.156426803s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:16.194004Z","caller":"traceutil/trace.go:171","msg":"trace[1240703436] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:532; }","duration":"1.144188093s","start":"2026-04-27T19:44:15.049786Z","end":"2026-04-27T19:44:16.193974Z","steps":["trace[1240703436] 'read index received' (duration: 1.062421963s)","trace[1240703436] 'applied index is now lower than readState.Index' (duration: 81.76489ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:44:16.194089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:15.036757Z","time spent":"1.157273064s","remote":"127.0.0.1:33236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:44:16.194392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144586383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.191\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-27T19:44:16.19447Z","caller":"traceutil/trace.go:171","msg":"trace[739640014] range","detail":"{range_begin:/registry/masterleases/199.19.213.191; range_end:; response_count:1; response_revision:526; }","duration":"1.144705136s","start":"2026-04-27T19:44:15.049752Z","end":"2026-04-27T19:44:16.194457Z","steps":["trace[739640014] 'agreement among raft nodes before linearized reading' (duration: 1.144323036s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:16.194635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:15.037499Z","time spent":"1.15673072s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:44:16.194684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:15.036788Z","time spent":"1.157421578s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:44:16.194514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:15.049738Z","time spent":"1.144764617s","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.191\" "} {"level":"warn","ts":"2026-04-27T19:44:16.695423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903911468292287348,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:44:17.196311Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903911468292287348,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:44:17.23989Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.046254203s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T19:44:17.240412Z","caller":"traceutil/trace.go:171","msg":"trace[134512827] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"1.123241592s","start":"2026-04-27T19:44:16.117141Z","end":"2026-04-27T19:44:17.240382Z","steps":["trace[134512827] 'process raft request' (duration: 1.122926774s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:17.240475Z","caller":"traceutil/trace.go:171","msg":"trace[900026117] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.114574453s","start":"2026-04-27T19:44:16.125858Z","end":"2026-04-27T19:44:17.240433Z","steps":["trace[900026117] 'process raft request' (duration: 1.114388429s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:17.24054Z","caller":"traceutil/trace.go:171","msg":"trace[477282213] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.113647498s","start":"2026-04-27T19:44:16.126871Z","end":"2026-04-27T19:44:17.240519Z","steps":["trace[477282213] 'process raft request' (duration: 1.113428313s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:17.240625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:16.126865Z","time spent":"1.11371043s","remote":"127.0.0.1:33236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:44:17.240651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:16.125842Z","time spent":"1.114712406s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:44:17.24065Z","caller":"traceutil/trace.go:171","msg":"trace[229764659] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.10696893s","start":"2026-04-27T19:44:16.133657Z","end":"2026-04-27T19:44:17.240626Z","steps":["trace[229764659] 'process raft request' (duration: 1.106870928s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:17.240639Z","caller":"traceutil/trace.go:171","msg":"trace[423081440] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.111921086s","start":"2026-04-27T19:44:16.128665Z","end":"2026-04-27T19:44:17.240586Z","steps":["trace[423081440] 'process raft request' (duration: 1.111763262s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:17.240739Z","caller":"traceutil/trace.go:171","msg":"trace[298157831] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.113795733s","start":"2026-04-27T19:44:16.126939Z","end":"2026-04-27T19:44:17.240734Z","steps":["trace[298157831] 'process raft request' (duration: 1.113467435s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:17.240768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:16.126931Z","time spent":"1.113826853s","remote":"127.0.0.1:33236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:44:17.240804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:16.133638Z","time spent":"1.107079972s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:44:17.240827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:16.128645Z","time spent":"1.11209909s","remote":"127.0.0.1:33236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:44:17.240843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:16.11712Z","time spent":"1.123400556s","remote":"127.0.0.1:33236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:44:17.240716Z","caller":"traceutil/trace.go:171","msg":"trace[1577900753] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.107354069s","start":"2026-04-27T19:44:16.133355Z","end":"2026-04-27T19:44:17.240709Z","steps":["trace[1577900753] 'process raft request' (duration: 1.107114993s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:17.241074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:16.133333Z","time spent":"1.107668498s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:44:17.242339Z","caller":"traceutil/trace.go:171","msg":"trace[1677468591] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.103627416s","start":"2026-04-27T19:44:16.138678Z","end":"2026-04-27T19:44:17.242306Z","steps":["trace[1677468591] 'process raft request' (duration: 1.101899572s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:17.242472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:16.138662Z","time spent":"1.103759449s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:44:17.243379Z","caller":"traceutil/trace.go:171","msg":"trace[1575120002] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.057738692s","start":"2026-04-27T19:44:16.185592Z","end":"2026-04-27T19:44:17.243331Z","steps":["trace[1575120002] 'process raft request' (duration: 1.055414735s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:17.243526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:16.18557Z","time spent":"1.057894197s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:44:17.249714Z","caller":"traceutil/trace.go:171","msg":"trace[693084569] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:546; }","duration":"1.055315262s","start":"2026-04-27T19:44:16.194334Z","end":"2026-04-27T19:44:17.249649Z","steps":["trace[693084569] 'read index received' (duration: 1.045743039s)","trace[693084569] 'applied index is now lower than readState.Index' (duration: 9.570603ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:44:17.2499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:16.195822Z","time spent":"1.054075361s","remote":"127.0.0.1:32780","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-27T19:44:17.250167Z","caller":"traceutil/trace.go:171","msg":"trace[1221824595] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.049553777s","start":"2026-04-27T19:44:16.200594Z","end":"2026-04-27T19:44:17.250148Z","steps":["trace[1221824595] 'process raft request' (duration: 1.048572431s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:17.250269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:16.20058Z","time spent":"1.049636688s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:44:17.250389Z","caller":"traceutil/trace.go:171","msg":"trace[920938491] transaction","detail":"{read_only:false; number_of_response:1; response_revision:536; }","duration":"1.046342906s","start":"2026-04-27T19:44:16.204039Z","end":"2026-04-27T19:44:17.250382Z","steps":["trace[920938491] 'process raft request' (duration: 1.045344501s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:17.250424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:16.204013Z","time spent":"1.046390947s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:44:17.253502Z","caller":"traceutil/trace.go:171","msg":"trace[208168330] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.041451712s","start":"2026-04-27T19:44:16.209537Z","end":"2026-04-27T19:44:17.250988Z","steps":["trace[208168330] 'process raft request' (duration: 1.039909923s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:17.255411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.607827833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-27T19:44:17.255481Z","caller":"traceutil/trace.go:171","msg":"trace[1689238065] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"1.607941186s","start":"2026-04-27T19:44:15.647523Z","end":"2026-04-27T19:44:17.255464Z","steps":["trace[1689238065] 'agreement among raft nodes before linearized reading' (duration: 1.60293959s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:17.255523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:15.647503Z","time spent":"1.608012289s","remote":"127.0.0.1:33020","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-04-27T19:44:17.255789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002245058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:44:17.255845Z","caller":"traceutil/trace.go:171","msg":"trace[1989126296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"1.0023401s","start":"2026-04-27T19:44:16.253492Z","end":"2026-04-27T19:44:17.255833Z","steps":["trace[1989126296] 'agreement among raft nodes before linearized reading' (duration: 996.979875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:17.255883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:16.253473Z","time spent":"1.002400893s","remote":"127.0.0.1:60966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:44:17.256059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.745590322s","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-04-27T19:44:17.256093Z","caller":"traceutil/trace.go:171","msg":"trace[777273897] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"1.745660314s","start":"2026-04-27T19:44:15.510424Z","end":"2026-04-27T19:44:17.256084Z","steps":["trace[777273897] 'agreement among raft nodes before linearized reading' (duration: 1.740040622s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:17.25612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:15.510405Z","time spent":"1.745708815s","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:44:17.256333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:16.209488Z","time spent":"1.044093168s","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:44:17.256531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.603615337s","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-04-27T19:44:17.256579Z","caller":"traceutil/trace.go:171","msg":"trace[196770424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"1.603690398s","start":"2026-04-27T19:44:15.652875Z","end":"2026-04-27T19:44:17.256566Z","steps":["trace[196770424] 'agreement among raft nodes before linearized reading' (duration: 1.597584563s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:17.256622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:15.652865Z","time spent":"1.60374771s","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T19:44:17.257585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.604353485s","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-04-27T19:44:17.257646Z","caller":"traceutil/trace.go:171","msg":"trace[1241510134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"1.604432886s","start":"2026-04-27T19:44:15.653197Z","end":"2026-04-27T19:44:17.25763Z","steps":["trace[1241510134] 'agreement among raft nodes before linearized reading' (duration: 1.597260285s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:17.257687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:15.653188Z","time spent":"1.60449036s","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T19:44:23.519997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.169274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:44:23.52015Z","caller":"traceutil/trace.go:171","msg":"trace[836953496] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"207.050543ms","start":"2026-04-27T19:44:23.313081Z","end":"2026-04-27T19:44:23.520131Z","steps":["trace[836953496] 'process raft request' (duration: 39.662884ms)","trace[836953496] 'compare' (duration: 167.03737ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:44:23.520364Z","caller":"traceutil/trace.go:171","msg":"trace[1526278950] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"153.109737ms","start":"2026-04-27T19:44:23.367229Z","end":"2026-04-27T19:44:23.520339Z","steps":["trace[1526278950] 'process raft request' (duration: 153.069266ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:23.520533Z","caller":"traceutil/trace.go:171","msg":"trace[1664528775] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"207.316779ms","start":"2026-04-27T19:44:23.313204Z","end":"2026-04-27T19:44:23.520521Z","steps":["trace[1664528775] 'process raft request' (duration: 207.035342ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:23.520505Z","caller":"traceutil/trace.go:171","msg":"trace[2118589116] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"207.289198ms","start":"2026-04-27T19:44:23.313177Z","end":"2026-04-27T19:44:23.520466Z","steps":["trace[2118589116] 'process raft request' (duration: 206.93338ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:24.32592Z","caller":"traceutil/trace.go:171","msg":"trace[238289882] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"100.025903ms","start":"2026-04-27T19:44:24.225861Z","end":"2026-04-27T19:44:24.325887Z","steps":["trace[238289882] 'process raft request' (duration: 84.728555ms)","trace[238289882] 'compare' (duration: 15.101934ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:44:25.551752Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903911468292287587,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T19:44:25.869432Z","caller":"traceutil/trace.go:171","msg":"trace[1712954656] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"818.514956ms","start":"2026-04-27T19:44:25.050895Z","end":"2026-04-27T19:44:25.86941Z","steps":["trace[1712954656] 'read index received' (duration: 818.235809ms)","trace[1712954656] 'applied index is now lower than readState.Index' (duration: 278.197µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:44:25.869601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.694991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.191\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-27T19:44:25.869625Z","caller":"traceutil/trace.go:171","msg":"trace[1018035217] range","detail":"{range_begin:/registry/masterleases/199.19.213.191; range_end:; response_count:1; response_revision:633; }","duration":"818.768473ms","start":"2026-04-27T19:44:25.050848Z","end":"2026-04-27T19:44:25.869617Z","steps":["trace[1018035217] 'agreement among raft nodes before linearized reading' (duration: 818.667921ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:25.86955Z","caller":"traceutil/trace.go:171","msg":"trace[1917762615] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"959.946267ms","start":"2026-04-27T19:44:24.909563Z","end":"2026-04-27T19:44:25.869509Z","steps":["trace[1917762615] 'process raft request' (duration: 959.65923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:25.86965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:25.050797Z","time spent":"818.846655ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.191\" "} {"level":"warn","ts":"2026-04-27T19:44:25.869756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:24.909538Z","time spent":"960.129351ms","remote":"127.0.0.1:32936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:44:25.869856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.994563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:44:25.869875Z","caller":"traceutil/trace.go:171","msg":"trace[1033290116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"617.039823ms","start":"2026-04-27T19:44:25.25283Z","end":"2026-04-27T19:44:25.86987Z","steps":["trace[1033290116] 'agreement among raft nodes before linearized reading' (duration: 617.008033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:25.869891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:25.252817Z","time spent":"617.071545ms","remote":"127.0.0.1:60966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:44:25.869986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.294018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-27T19:44:25.869977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.960421ms","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-04-27T19:44:25.870003Z","caller":"traceutil/trace.go:171","msg":"trace[1596944936] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"346.32253ms","start":"2026-04-27T19:44:25.523675Z","end":"2026-04-27T19:44:25.869998Z","steps":["trace[1596944936] 'agreement among raft nodes before linearized reading' (duration: 346.288599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:25.870019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:25.523668Z","time spent":"346.34729ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T19:44:25.870024Z","caller":"traceutil/trace.go:171","msg":"trace[823710839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"346.014542ms","start":"2026-04-27T19:44:25.523993Z","end":"2026-04-27T19:44:25.870007Z","steps":["trace[823710839] 'agreement among raft nodes before linearized reading' (duration: 345.894269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:25.870064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:25.523986Z","time spent":"346.069633ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T19:44:25.870187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.942661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-27T19:44:25.87023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.585876ms","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-04-27T19:44:25.870251Z","caller":"traceutil/trace.go:171","msg":"trace[513448395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"551.042293ms","start":"2026-04-27T19:44:25.319191Z","end":"2026-04-27T19:44:25.870233Z","steps":["trace[513448395] 'agreement among raft nodes before linearized reading' (duration: 550.852499ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:25.870262Z","caller":"traceutil/trace.go:171","msg":"trace[1185519305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"346.652408ms","start":"2026-04-27T19:44:25.523604Z","end":"2026-04-27T19:44:25.870256Z","steps":["trace[1185519305] 'agreement among raft nodes before linearized reading' (duration: 346.593727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:25.87029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:25.523587Z","time spent":"346.697999ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T19:44:25.870297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:25.319114Z","time spent":"551.172147ms","remote":"127.0.0.1:33020","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":"info","ts":"2026-04-27T19:44:26.191377Z","caller":"traceutil/trace.go:171","msg":"trace[89804653] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"316.968607ms","start":"2026-04-27T19:44:25.874383Z","end":"2026-04-27T19:44:26.191352Z","steps":["trace[89804653] 'process raft request' (duration: 316.538405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:26.191602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:25.874362Z","time spent":"317.151201ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:44:26.191802Z","caller":"traceutil/trace.go:171","msg":"trace[194067591] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"316.295869ms","start":"2026-04-27T19:44:25.875486Z","end":"2026-04-27T19:44:26.191782Z","steps":["trace[194067591] 'process raft request' (duration: 315.811757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:26.191912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:25.875476Z","time spent":"316.387041ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:44:26.192182Z","caller":"traceutil/trace.go:171","msg":"trace[130445993] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"316.598767ms","start":"2026-04-27T19:44:25.875545Z","end":"2026-04-27T19:44:26.192144Z","steps":["trace[130445993] 'process raft request' (duration: 316.158846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:26.192414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:25.875539Z","time spent":"316.702289ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:44:26.192622Z","caller":"traceutil/trace.go:171","msg":"trace[888323152] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"316.729999ms","start":"2026-04-27T19:44:25.875878Z","end":"2026-04-27T19:44:26.192608Z","steps":["trace[888323152] 'process raft request' (duration: 316.208336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:26.192702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:25.875822Z","time spent":"316.843203ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:44:26.236613Z","caller":"traceutil/trace.go:171","msg":"trace[1027547625] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"288.240549ms","start":"2026-04-27T19:44:25.948352Z","end":"2026-04-27T19:44:26.236593Z","steps":["trace[1027547625] 'process raft request' (duration: 288.124116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:28.854563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.600952977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:44:28.854639Z","caller":"traceutil/trace.go:171","msg":"trace[1649025656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"1.60106885s","start":"2026-04-27T19:44:27.253556Z","end":"2026-04-27T19:44:28.854625Z","steps":["trace[1649025656] 'range keys from in-memory index tree' (duration: 1.600883746s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:28.854669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:27.253539Z","time spent":"1.601123071s","remote":"127.0.0.1:60966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:44:28.854661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.821412ms","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-04-27T19:44:28.854708Z","caller":"traceutil/trace.go:171","msg":"trace[1573972194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"658.940235ms","start":"2026-04-27T19:44:28.195755Z","end":"2026-04-27T19:44:28.854696Z","steps":["trace[1573972194] 'range keys from in-memory index tree' (duration: 658.756071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:28.854738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:28.195739Z","time spent":"658.991996ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T19:44:28.854763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.661198ms","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-04-27T19:44:28.854787Z","caller":"traceutil/trace.go:171","msg":"trace[1875840185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"658.699669ms","start":"2026-04-27T19:44:28.19608Z","end":"2026-04-27T19:44:28.85478Z","steps":["trace[1875840185] 'range keys from in-memory index tree' (duration: 658.619097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:28.854804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:28.196073Z","time spent":"658.72602ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T19:44:28.854835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.699224ms","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-04-27T19:44:28.854902Z","caller":"traceutil/trace.go:171","msg":"trace[172240775] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"657.815046ms","start":"2026-04-27T19:44:28.197069Z","end":"2026-04-27T19:44:28.854884Z","steps":["trace[172240775] 'range keys from in-memory index tree' (duration: 657.56805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:28.854945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:28.197041Z","time spent":"657.895049ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:44:28.855047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.659848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-27T19:44:28.855093Z","caller":"traceutil/trace.go:171","msg":"trace[294790552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"658.721179ms","start":"2026-04-27T19:44:28.196357Z","end":"2026-04-27T19:44:28.855078Z","steps":["trace[294790552] 'range keys from in-memory index tree' (duration: 658.596936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:28.855124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:28.196352Z","time spent":"658.764961ms","remote":"127.0.0.1:33020","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":"info","ts":"2026-04-27T19:44:31.437921Z","caller":"traceutil/trace.go:171","msg":"trace[1486004269] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"120.618894ms","start":"2026-04-27T19:44:31.317279Z","end":"2026-04-27T19:44:31.437898Z","steps":["trace[1486004269] 'read index received' (duration: 120.368828ms)","trace[1486004269] 'applied index is now lower than readState.Index' (duration: 249.016µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:44:31.438096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.802249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:44:31.438074Z","caller":"traceutil/trace.go:171","msg":"trace[823993946] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"319.232043ms","start":"2026-04-27T19:44:31.118819Z","end":"2026-04-27T19:44:31.438051Z","steps":["trace[823993946] 'process raft request' (duration: 318.939716ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:31.438137Z","caller":"traceutil/trace.go:171","msg":"trace[1171693306] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:676; }","duration":"120.894041ms","start":"2026-04-27T19:44:31.317227Z","end":"2026-04-27T19:44:31.438121Z","steps":["trace[1171693306] 'agreement among raft nodes before linearized reading' (duration: 120.775478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:31.438237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:31.118791Z","time spent":"319.371717ms","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:44:34.401147Z","caller":"traceutil/trace.go:171","msg":"trace[101322699] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"248.135232ms","start":"2026-04-27T19:44:34.152981Z","end":"2026-04-27T19:44:34.401116Z","steps":["trace[101322699] 'process raft request' (duration: 247.899137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:34.401657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.305399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:44:34.401714Z","caller":"traceutil/trace.go:171","msg":"trace[1980583349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"145.400212ms","start":"2026-04-27T19:44:34.256299Z","end":"2026-04-27T19:44:34.4017Z","steps":["trace[1980583349] 'agreement among raft nodes before linearized reading' (duration: 144.969731ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:34.40161Z","caller":"traceutil/trace.go:171","msg":"trace[706311603] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:739; }","duration":"144.706825ms","start":"2026-04-27T19:44:34.256337Z","end":"2026-04-27T19:44:34.401044Z","steps":["trace[706311603] 'read index received' (duration: 144.377786ms)","trace[706311603] 'applied index is now lower than readState.Index' (duration: 327.909µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:44:43.253374Z","caller":"traceutil/trace.go:171","msg":"trace[1576831371] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"233.098722ms","start":"2026-04-27T19:44:43.020249Z","end":"2026-04-27T19:44:43.253348Z","steps":["trace[1576831371] 'read index received' (duration: 232.831295ms)","trace[1576831371] 'applied index is now lower than readState.Index' (duration: 266.557µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:44:43.253424Z","caller":"traceutil/trace.go:171","msg":"trace[1111699162] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"286.453383ms","start":"2026-04-27T19:44:42.966928Z","end":"2026-04-27T19:44:43.253382Z","steps":["trace[1111699162] 'process raft request' (duration: 286.207607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:43.253717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.43922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-27T19:44:43.253772Z","caller":"traceutil/trace.go:171","msg":"trace[88269447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:781; }","duration":"233.543403ms","start":"2026-04-27T19:44:43.020217Z","end":"2026-04-27T19:44:43.253761Z","steps":["trace[88269447] 'agreement among raft nodes before linearized reading' (duration: 233.318768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:43.25466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.071814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-27T19:44:43.254722Z","caller":"traceutil/trace.go:171","msg":"trace[2047301237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:781; }","duration":"222.154296ms","start":"2026-04-27T19:44:43.032552Z","end":"2026-04-27T19:44:43.254706Z","steps":["trace[2047301237] 'agreement among raft nodes before linearized reading' (duration: 221.988812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:43.25472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.331937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-27T19:44:43.254747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.321563ms","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-04-27T19:44:43.254791Z","caller":"traceutil/trace.go:171","msg":"trace[404469008] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:781; }","duration":"214.43805ms","start":"2026-04-27T19:44:43.040336Z","end":"2026-04-27T19:44:43.254774Z","steps":["trace[404469008] 'agreement among raft nodes before linearized reading' (duration: 214.180034ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:43.254819Z","caller":"traceutil/trace.go:171","msg":"trace[206821931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:781; }","duration":"217.460807ms","start":"2026-04-27T19:44:43.03734Z","end":"2026-04-27T19:44:43.254801Z","steps":["trace[206821931] 'agreement among raft nodes before linearized reading' (duration: 217.19432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:46.746882Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903911468292287926,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:44:47.087839Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.062060003s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T19:44:47.088241Z","caller":"traceutil/trace.go:171","msg":"trace[1990198699] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"842.046931ms","start":"2026-04-27T19:44:46.246168Z","end":"2026-04-27T19:44:47.088215Z","steps":["trace[1990198699] 'read index received' (duration: 841.844486ms)","trace[1990198699] 'applied index is now lower than readState.Index' (duration: 201.605µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:44:47.08839Z","caller":"traceutil/trace.go:171","msg":"trace[1503123558] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"1.062821451s","start":"2026-04-27T19:44:46.025553Z","end":"2026-04-27T19:44:47.088374Z","steps":["trace[1503123558] 'process raft request' (duration: 1.062474192s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:47.088535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.134686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:44:47.088599Z","caller":"traceutil/trace.go:171","msg":"trace[1218407806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"835.220678ms","start":"2026-04-27T19:44:46.253351Z","end":"2026-04-27T19:44:47.088572Z","steps":["trace[1218407806] 'agreement among raft nodes before linearized reading' (duration: 835.145276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:47.088652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:46.253337Z","time spent":"835.307761ms","remote":"127.0.0.1:60966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:44:47.088655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:46.025536Z","time spent":"1.062951035s","remote":"127.0.0.1:33006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:44:47.08867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.499873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:44:47.088733Z","caller":"traceutil/trace.go:171","msg":"trace[539091805] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:802; }","duration":"842.600606ms","start":"2026-04-27T19:44:46.246117Z","end":"2026-04-27T19:44:47.088718Z","steps":["trace[539091805] 'agreement among raft nodes before linearized reading' (duration: 842.501633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:44:47.088779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:44:46.246075Z","time spent":"842.690618ms","remote":"127.0.0.1:32994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-04-27T19:44:59.773731Z","caller":"traceutil/trace.go:171","msg":"trace[1932871770] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"227.656694ms","start":"2026-04-27T19:44:59.546064Z","end":"2026-04-27T19:44:59.773721Z","steps":["trace[1932871770] 'process raft request' (duration: 227.524691ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:59.773713Z","caller":"traceutil/trace.go:171","msg":"trace[648150432] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"227.561692ms","start":"2026-04-27T19:44:59.546122Z","end":"2026-04-27T19:44:59.773683Z","steps":["trace[648150432] 'process raft request' (duration: 227.514321ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:59.773713Z","caller":"traceutil/trace.go:171","msg":"trace[1537259579] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"227.655714ms","start":"2026-04-27T19:44:59.546036Z","end":"2026-04-27T19:44:59.773692Z","steps":["trace[1537259579] 'process raft request' (duration: 227.4831ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:44:59.773835Z","caller":"traceutil/trace.go:171","msg":"trace[52455119] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"228.552246ms","start":"2026-04-27T19:44:59.545254Z","end":"2026-04-27T19:44:59.773807Z","steps":["trace[52455119] 'process raft request' (duration: 153.003163ms)","trace[52455119] 'compare' (duration: 74.994239ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:45:00.045464Z","caller":"traceutil/trace.go:171","msg":"trace[1438861796] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"108.793825ms","start":"2026-04-27T19:44:59.936644Z","end":"2026-04-27T19:45:00.045438Z","steps":["trace[1438861796] 'process raft request' (duration: 108.655412ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:45:09.715097Z","caller":"traceutil/trace.go:171","msg":"trace[332509205] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"129.90595ms","start":"2026-04-27T19:45:09.585167Z","end":"2026-04-27T19:45:09.715073Z","steps":["trace[332509205] 'process raft request' (duration: 129.746746ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:45:21.94794Z","caller":"traceutil/trace.go:171","msg":"trace[752722871] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"183.273339ms","start":"2026-04-27T19:45:21.764645Z","end":"2026-04-27T19:45:21.947918Z","steps":["trace[752722871] 'process raft request' (duration: 183.114455ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:45:21.965036Z","caller":"traceutil/trace.go:171","msg":"trace[847238462] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1059; }","duration":"118.25486ms","start":"2026-04-27T19:45:21.846761Z","end":"2026-04-27T19:45:21.965016Z","steps":["trace[847238462] 'read index received' (duration: 100.977941ms)","trace[847238462] 'applied index is now lower than readState.Index' (duration: 17.275859ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:45:21.965228Z","caller":"traceutil/trace.go:171","msg":"trace[1831685488] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"149.605595ms","start":"2026-04-27T19:45:21.815614Z","end":"2026-04-27T19:45:21.965219Z","steps":["trace[1831685488] 'process raft request' (duration: 149.269856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:21.965369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.597938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:45:21.965412Z","caller":"traceutil/trace.go:171","msg":"trace[2038109556] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1019; }","duration":"118.648039ms","start":"2026-04-27T19:45:21.846747Z","end":"2026-04-27T19:45:21.965395Z","steps":["trace[2038109556] 'agreement among raft nodes before linearized reading' (duration: 118.584447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:23.315645Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903911468292288398,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:45:23.815913Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903911468292288398,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:45:23.929564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.77805697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:45:23.929637Z","caller":"traceutil/trace.go:171","msg":"trace[1671219826] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1023; }","duration":"1.778145502s","start":"2026-04-27T19:45:22.151476Z","end":"2026-04-27T19:45:23.929621Z","steps":["trace[1671219826] 'count revisions from in-memory index tree' (duration: 1.777953266s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:23.929675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:22.151409Z","time spent":"1.778256184s","remote":"127.0.0.1:33010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:45:23.929863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.677531489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:45:23.929897Z","caller":"traceutil/trace.go:171","msg":"trace[1468299958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"1.677600201s","start":"2026-04-27T19:45:22.252288Z","end":"2026-04-27T19:45:23.929889Z","steps":["trace[1468299958] 'range keys from in-memory index tree' (duration: 1.677454448s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:23.929922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:22.252255Z","time spent":"1.677661072s","remote":"127.0.0.1:60966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:45:23.929974Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.652268609s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-27T19:45:23.930037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115243337s","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-04-27T19:45:23.930103Z","caller":"traceutil/trace.go:171","msg":"trace[1759094484] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1023; }","duration":"1.115310159s","start":"2026-04-27T19:45:22.814785Z","end":"2026-04-27T19:45:23.930095Z","steps":["trace[1759094484] 'range keys from in-memory index tree' (duration: 1.115235667s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:45:23.930382Z","caller":"traceutil/trace.go:171","msg":"trace[947726645] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"1.114918909s","start":"2026-04-27T19:45:22.815447Z","end":"2026-04-27T19:45:23.930365Z","steps":["trace[947726645] 'read index received' (duration: 1.114618432s)","trace[947726645] 'applied index is now lower than readState.Index' (duration: 299.577µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:45:23.930438Z","caller":"traceutil/trace.go:171","msg":"trace[384210529] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"1.652807873s","start":"2026-04-27T19:45:22.277606Z","end":"2026-04-27T19:45:23.930414Z","steps":["trace[384210529] 'process raft request' (duration: 1.652508075s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:23.930512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115052043s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-27T19:45:23.930562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.208068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T19:45:23.930573Z","caller":"traceutil/trace.go:171","msg":"trace[1561234285] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1024; }","duration":"1.115161926s","start":"2026-04-27T19:45:22.815398Z","end":"2026-04-27T19:45:23.93056Z","steps":["trace[1561234285] 'agreement among raft nodes before linearized reading' (duration: 1.115027572s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:23.93062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:22.815378Z","time spent":"1.115221597s","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-04-27T19:45:23.930602Z","caller":"traceutil/trace.go:171","msg":"trace[251443262] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1024; }","duration":"254.28086ms","start":"2026-04-27T19:45:23.676311Z","end":"2026-04-27T19:45:23.930591Z","steps":["trace[251443262] 'agreement among raft nodes before linearized reading' (duration: 254.168046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:23.930608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:22.277586Z","time spent":"1.652926365s","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:45:24.493571Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903911468292288404,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T19:45:24.635536Z","caller":"traceutil/trace.go:171","msg":"trace[1323464927] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"680.07546ms","start":"2026-04-27T19:45:23.955423Z","end":"2026-04-27T19:45:24.635498Z","steps":["trace[1323464927] 'process raft request' (duration: 654.688096ms)","trace[1323464927] 'compare' (duration: 25.206479ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:45:24.635737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.707821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-27T19:45:24.635734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:23.955407Z","time spent":"680.237533ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:45:24.635794Z","caller":"traceutil/trace.go:171","msg":"trace[186301652] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1025; }","duration":"642.821234ms","start":"2026-04-27T19:45:23.992959Z","end":"2026-04-27T19:45:24.63578Z","steps":["trace[186301652] 'agreement among raft nodes before linearized reading' (duration: 642.636669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:24.635863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:23.992938Z","time spent":"642.907796ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T19:45:24.635517Z","caller":"traceutil/trace.go:171","msg":"trace[925989084] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1066; }","duration":"642.471065ms","start":"2026-04-27T19:45:23.993015Z","end":"2026-04-27T19:45:24.635486Z","steps":["trace[925989084] 'read index received' (duration: 617.00825ms)","trace[925989084] 'applied index is now lower than readState.Index' (duration: 25.461635ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:45:24.6364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.939526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T19:45:24.636429Z","caller":"traceutil/trace.go:171","msg":"trace[979724583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1025; }","duration":"610.984707ms","start":"2026-04-27T19:45:24.025437Z","end":"2026-04-27T19:45:24.636421Z","steps":["trace[979724583] 'agreement among raft nodes before linearized reading' (duration: 610.880574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:24.636453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:24.025431Z","time spent":"611.014217ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T19:45:24.636442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.137301ms","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-04-27T19:45:24.636493Z","caller":"traceutil/trace.go:171","msg":"trace[562262966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1025; }","duration":"611.202923ms","start":"2026-04-27T19:45:24.025275Z","end":"2026-04-27T19:45:24.636478Z","steps":["trace[562262966] 'agreement among raft nodes before linearized reading' (duration: 610.966676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:24.636531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:24.025268Z","time spent":"611.254714ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T19:45:24.636596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.159622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-27T19:45:24.636649Z","caller":"traceutil/trace.go:171","msg":"trace[1460809409] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1025; }","duration":"611.269205ms","start":"2026-04-27T19:45:24.025365Z","end":"2026-04-27T19:45:24.636634Z","steps":["trace[1460809409] 'agreement among raft nodes before linearized reading' (duration: 611.108711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:24.63669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:24.025344Z","time spent":"611.337796ms","remote":"127.0.0.1:33020","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":"info","ts":"2026-04-27T19:45:36.985159Z","caller":"traceutil/trace.go:171","msg":"trace[1454153556] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"151.683518ms","start":"2026-04-27T19:45:36.833446Z","end":"2026-04-27T19:45:36.985129Z","steps":["trace[1454153556] 'process raft request' (duration: 151.564295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:49.475481Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903911468292288621,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T19:45:49.594251Z","caller":"traceutil/trace.go:171","msg":"trace[564621220] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"724.423465ms","start":"2026-04-27T19:45:48.869803Z","end":"2026-04-27T19:45:49.594226Z","steps":["trace[564621220] 'process raft request' (duration: 724.24118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:49.594398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:48.869783Z","time spent":"724.537327ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:45:49.609931Z","caller":"traceutil/trace.go:171","msg":"trace[1004847187] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"737.864995ms","start":"2026-04-27T19:45:48.872042Z","end":"2026-04-27T19:45:49.609907Z","steps":["trace[1004847187] 'process raft request' (duration: 737.708251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:49.61013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:48.872025Z","time spent":"737.983217ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:45:49.610055Z","caller":"traceutil/trace.go:171","msg":"trace[122559683] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1151; }","duration":"634.942734ms","start":"2026-04-27T19:45:48.97509Z","end":"2026-04-27T19:45:49.610033Z","steps":["trace[122559683] 'read index received' (duration: 618.958569ms)","trace[122559683] 'applied index is now lower than readState.Index' (duration: 15.982405ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:45:49.610287Z","caller":"traceutil/trace.go:171","msg":"trace[567165083] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"737.139997ms","start":"2026-04-27T19:45:48.873083Z","end":"2026-04-27T19:45:49.610223Z","steps":["trace[567165083] 'process raft request' (duration: 736.789248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:49.61037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.281863ms","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-04-27T19:45:49.610413Z","caller":"traceutil/trace.go:171","msg":"trace[985703362] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1107; }","duration":"635.364465ms","start":"2026-04-27T19:45:48.975033Z","end":"2026-04-27T19:45:49.610398Z","steps":["trace[985703362] 'agreement among raft nodes before linearized reading' (duration: 635.223481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:49.610444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:48.975014Z","time spent":"635.423076ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:45:49.610671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.81671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:45:49.610706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.451236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-27T19:45:49.610708Z","caller":"traceutil/trace.go:171","msg":"trace[1908491476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1107; }","duration":"356.893101ms","start":"2026-04-27T19:45:49.253804Z","end":"2026-04-27T19:45:49.610697Z","steps":["trace[1908491476] 'agreement among raft nodes before linearized reading' (duration: 356.83621ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:45:49.610738Z","caller":"traceutil/trace.go:171","msg":"trace[1399010453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1107; }","duration":"568.557649ms","start":"2026-04-27T19:45:49.04217Z","end":"2026-04-27T19:45:49.610728Z","steps":["trace[1399010453] 'agreement among raft nodes before linearized reading' (duration: 568.464787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:49.610741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:49.253784Z","time spent":"356.951413ms","remote":"127.0.0.1:60966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:45:49.610734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.058371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-27T19:45:49.610768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:49.042142Z","time spent":"568.620561ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T19:45:49.610791Z","caller":"traceutil/trace.go:171","msg":"trace[433200062] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1107; }","duration":"565.148034ms","start":"2026-04-27T19:45:49.045629Z","end":"2026-04-27T19:45:49.610777Z","steps":["trace[433200062] 'agreement among raft nodes before linearized reading' (duration: 565.021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:49.610812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:48.87306Z","time spent":"737.332801ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:45:49.610829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:49.045612Z","time spent":"565.208775ms","remote":"127.0.0.1:33094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-04-27T19:45:49.72587Z","caller":"traceutil/trace.go:171","msg":"trace[1372756653] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"110.517964ms","start":"2026-04-27T19:45:49.61533Z","end":"2026-04-27T19:45:49.725848Z","steps":["trace[1372756653] 'process raft request' (duration: 110.449072ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:45:49.725943Z","caller":"traceutil/trace.go:171","msg":"trace[732787721] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"110.841242ms","start":"2026-04-27T19:45:49.615089Z","end":"2026-04-27T19:45:49.72593Z","steps":["trace[732787721] 'process raft request' (duration: 37.782719ms)","trace[732787721] 'compare' (duration: 72.314004ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:45:50.676779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.147368ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-27T19:45:50.67688Z","caller":"traceutil/trace.go:171","msg":"trace[566995724] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1157; }","duration":"132.271274ms","start":"2026-04-27T19:45:50.54459Z","end":"2026-04-27T19:45:50.676861Z","steps":["trace[566995724] 'read index received' (duration: 37.471µs)","trace[566995724] 'applied index is now lower than readState.Index' (duration: 132.232463ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:45:50.676953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.351666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T19:45:50.67698Z","caller":"traceutil/trace.go:171","msg":"trace[1924549925] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1110; }","duration":"132.424128ms","start":"2026-04-27T19:45:50.544546Z","end":"2026-04-27T19:45:50.67697Z","steps":["trace[1924549925] 'agreement among raft nodes before linearized reading' (duration: 132.360616ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:45:51.844852Z","caller":"traceutil/trace.go:171","msg":"trace[920477308] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1158; }","duration":"231.020159ms","start":"2026-04-27T19:45:51.613804Z","end":"2026-04-27T19:45:51.844825Z","steps":["trace[920477308] 'read index received' (duration: 230.807863ms)","trace[920477308] 'applied index is now lower than readState.Index' (duration: 211.356µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:45:51.844895Z","caller":"traceutil/trace.go:171","msg":"trace[1795930771] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"243.017824ms","start":"2026-04-27T19:45:51.601841Z","end":"2026-04-27T19:45:51.844859Z","steps":["trace[1795930771] 'process raft request' (duration: 242.844379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:51.845127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.353772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-27T19:45:51.845151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.326206ms","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-04-27T19:45:51.845183Z","caller":"traceutil/trace.go:171","msg":"trace[2097830379] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1111; }","duration":"230.475455ms","start":"2026-04-27T19:45:51.614695Z","end":"2026-04-27T19:45:51.845171Z","steps":["trace[2097830379] 'agreement among raft nodes before linearized reading' (duration: 230.285441ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:45:51.845206Z","caller":"traceutil/trace.go:171","msg":"trace[277713989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1111; }","duration":"231.437511ms","start":"2026-04-27T19:45:51.613752Z","end":"2026-04-27T19:45:51.845189Z","steps":["trace[277713989] 'agreement among raft nodes before linearized reading' (duration: 231.234476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:51.845382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.750266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-27T19:45:51.84573Z","caller":"traceutil/trace.go:171","msg":"trace[1361857693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1111; }","duration":"116.188426ms","start":"2026-04-27T19:45:51.72952Z","end":"2026-04-27T19:45:51.845708Z","steps":["trace[1361857693] 'agreement among raft nodes before linearized reading' (duration: 115.55285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:51.845912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.275508ms","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-04-27T19:45:51.845966Z","caller":"traceutil/trace.go:171","msg":"trace[952772251] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1111; }","duration":"116.371431ms","start":"2026-04-27T19:45:51.729581Z","end":"2026-04-27T19:45:51.845952Z","steps":["trace[952772251] 'agreement among raft nodes before linearized reading' (duration: 116.235947ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:45:54.289415Z","caller":"traceutil/trace.go:171","msg":"trace[19070434] linearizableReadLoop","detail":"{readStateIndex:1166; appliedIndex:1165; }","duration":"337.86494ms","start":"2026-04-27T19:45:53.951528Z","end":"2026-04-27T19:45:54.289393Z","steps":["trace[19070434] 'read index received' (duration: 337.796499ms)","trace[19070434] 'applied index is now lower than readState.Index' (duration: 67.381µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:45:54.289534Z","caller":"traceutil/trace.go:171","msg":"trace[301378452] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"431.407533ms","start":"2026-04-27T19:45:53.858114Z","end":"2026-04-27T19:45:54.289522Z","steps":["trace[301378452] 'process raft request' (duration: 431.145636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:54.289672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:53.858093Z","time spent":"431.474945ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:45:54.289796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.252961ms","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-04-27T19:45:54.289888Z","caller":"traceutil/trace.go:171","msg":"trace[326578498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1118; }","duration":"338.392804ms","start":"2026-04-27T19:45:53.951473Z","end":"2026-04-27T19:45:54.289866Z","steps":["trace[326578498] 'agreement among raft nodes before linearized reading' (duration: 338.181429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:54.289948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:53.951453Z","time spent":"338.482936ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:45:54.299736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.910564ms","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-04-27T19:45:54.299792Z","caller":"traceutil/trace.go:171","msg":"trace[116370389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1119; }","duration":"347.992797ms","start":"2026-04-27T19:45:53.951785Z","end":"2026-04-27T19:45:54.299778Z","steps":["trace[116370389] 'agreement among raft nodes before linearized reading' (duration: 347.834993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:54.299804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.789531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-27T19:45:54.299862Z","caller":"traceutil/trace.go:171","msg":"trace[1389082556] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1119; }","duration":"347.893284ms","start":"2026-04-27T19:45:53.951953Z","end":"2026-04-27T19:45:54.299846Z","steps":["trace[1389082556] 'agreement among raft nodes before linearized reading' (duration: 347.745211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:54.299918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:53.951927Z","time spent":"347.977826ms","remote":"127.0.0.1:33020","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-04-27T19:45:54.300012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.974456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-27T19:45:54.299826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:53.951774Z","time spent":"348.044298ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T19:45:54.300045Z","caller":"traceutil/trace.go:171","msg":"trace[1526630596] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1119; }","duration":"348.169351ms","start":"2026-04-27T19:45:53.951869Z","end":"2026-04-27T19:45:54.300038Z","steps":["trace[1526630596] 'agreement among raft nodes before linearized reading' (duration: 347.972926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:45:54.300122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:45:53.951858Z","time spent":"348.256133ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-27T19:46:04.064163Z","caller":"traceutil/trace.go:171","msg":"trace[1082714339] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1150; }","duration":"121.374408ms","start":"2026-04-27T19:46:03.942766Z","end":"2026-04-27T19:46:04.064141Z","steps":["trace[1082714339] 'process raft request' (duration: 121.291636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:04.537927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.93998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:46:04.538027Z","caller":"traceutil/trace.go:171","msg":"trace[1179753394] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1201; }","duration":"101.110854ms","start":"2026-04-27T19:46:04.436896Z","end":"2026-04-27T19:46:04.538007Z","steps":["trace[1179753394] 'count revisions from in-memory index tree' (duration: 100.840288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:04.538378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.597316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363686"} {"level":"info","ts":"2026-04-27T19:46:04.538452Z","caller":"traceutil/trace.go:171","msg":"trace[189514613] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1201; }","duration":"101.72071ms","start":"2026-04-27T19:46:04.436713Z","end":"2026-04-27T19:46:04.538434Z","steps":["trace[189514613] 'range keys from in-memory index tree' (duration: 101.381741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:06.378258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.636373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:46:06.378358Z","caller":"traceutil/trace.go:171","msg":"trace[1860303201] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1210; }","duration":"376.772347ms","start":"2026-04-27T19:46:06.001549Z","end":"2026-04-27T19:46:06.378321Z","steps":["trace[1860303201] 'count revisions from in-memory index tree' (duration: 376.52918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:06.378403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:06.001531Z","time spent":"376.860669ms","remote":"127.0.0.1:56692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:46:06.378598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.751119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:46:06.378661Z","caller":"traceutil/trace.go:171","msg":"trace[336622562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1210; }","duration":"123.850031ms","start":"2026-04-27T19:46:06.254801Z","end":"2026-04-27T19:46:06.378651Z","steps":["trace[336622562] 'range keys from in-memory index tree' (duration: 123.661696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:08.908114Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903911468292288863,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:46:09.409075Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903911468292288863,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:46:09.909507Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903911468292288863,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:46:10.157789Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.758323899s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T19:46:10.158127Z","caller":"traceutil/trace.go:171","msg":"trace[1291072745] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1269; }","duration":"1.751016743s","start":"2026-04-27T19:46:08.407089Z","end":"2026-04-27T19:46:10.158106Z","steps":["trace[1291072745] 'read index received' (duration: 1.750830608s)","trace[1291072745] 'applied index is now lower than readState.Index' (duration: 185.285µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:46:10.158237Z","caller":"traceutil/trace.go:171","msg":"trace[2104038504] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"1.758907913s","start":"2026-04-27T19:46:08.399299Z","end":"2026-04-27T19:46:10.158207Z","steps":["trace[2104038504] 'process raft request' (duration: 1.758678988s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:10.158292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.751185947s","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-04-27T19:46:10.158327Z","caller":"traceutil/trace.go:171","msg":"trace[1200274811] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1217; }","duration":"1.751267359s","start":"2026-04-27T19:46:08.407047Z","end":"2026-04-27T19:46:10.158315Z","steps":["trace[1200274811] 'agreement among raft nodes before linearized reading' (duration: 1.751138346s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:10.158367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:08.407031Z","time spent":"1.751327381s","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:46:10.158372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:08.399274Z","time spent":"1.759026697s","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:46:10.158448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.557783322s","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-04-27T19:46:10.158485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.748636153s","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-04-27T19:46:10.1586Z","caller":"traceutil/trace.go:171","msg":"trace[51819297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1217; }","duration":"1.748802047s","start":"2026-04-27T19:46:08.40978Z","end":"2026-04-27T19:46:10.158582Z","steps":["trace[51819297] 'agreement among raft nodes before linearized reading' (duration: 1.748508609s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:10.158674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:08.409762Z","time spent":"1.74890166s","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T19:46:10.158529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39843416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:46:10.158667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.558353746s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-27T19:46:10.158749Z","caller":"traceutil/trace.go:171","msg":"trace[1449083255] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1217; }","duration":"1.398687287s","start":"2026-04-27T19:46:08.760049Z","end":"2026-04-27T19:46:10.158737Z","steps":["trace[1449083255] 'agreement among raft nodes before linearized reading' (duration: 1.398457891s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:10.158788Z","caller":"traceutil/trace.go:171","msg":"trace[2102357685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1217; }","duration":"1.558547571s","start":"2026-04-27T19:46:08.600219Z","end":"2026-04-27T19:46:10.158767Z","steps":["trace[2102357685] 'agreement among raft nodes before linearized reading' (duration: 1.55809576s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:10.158842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:08.760029Z","time spent":"1.398755908s","remote":"127.0.0.1:56704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:46:10.158477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.558259644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-27T19:46:10.158873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:08.600216Z","time spent":"1.558643404s","remote":"127.0.0.1:33020","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":"info","ts":"2026-04-27T19:46:10.158901Z","caller":"traceutil/trace.go:171","msg":"trace[1039177479] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1217; }","duration":"1.558680074s","start":"2026-04-27T19:46:08.600205Z","end":"2026-04-27T19:46:10.158885Z","steps":["trace[1039177479] 'agreement among raft nodes before linearized reading' (duration: 1.558197522s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:10.159036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:08.600165Z","time spent":"1.558839019s","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T19:46:10.158481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.996175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:46:10.159246Z","caller":"traceutil/trace.go:171","msg":"trace[575309868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1217; }","duration":"907.786725ms","start":"2026-04-27T19:46:09.251446Z","end":"2026-04-27T19:46:10.159233Z","steps":["trace[575309868] 'agreement among raft nodes before linearized reading' (duration: 907.013255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:10.159287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:09.251427Z","time spent":"907.846907ms","remote":"127.0.0.1:60966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T19:46:10.158528Z","caller":"traceutil/trace.go:171","msg":"trace[1659886814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1217; }","duration":"1.557868364s","start":"2026-04-27T19:46:08.600639Z","end":"2026-04-27T19:46:10.158507Z","steps":["trace[1659886814] 'agreement among raft nodes before linearized reading' (duration: 1.557651568s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:10.159381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:08.600634Z","time spent":"1.558713746s","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T19:46:22.468829Z","caller":"traceutil/trace.go:171","msg":"trace[219752321] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"254.418658ms","start":"2026-04-27T19:46:22.214383Z","end":"2026-04-27T19:46:22.468801Z","steps":["trace[219752321] 'read index received' (duration: 254.198402ms)","trace[219752321] 'applied index is now lower than readState.Index' (duration: 219.076µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:46:22.468896Z","caller":"traceutil/trace.go:171","msg":"trace[861100836] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"270.447496ms","start":"2026-04-27T19:46:22.198401Z","end":"2026-04-27T19:46:22.468848Z","steps":["trace[861100836] 'process raft request' (duration: 270.23ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:22.469068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.545714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-27T19:46:22.469113Z","caller":"traceutil/trace.go:171","msg":"trace[279841927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1256; }","duration":"249.653546ms","start":"2026-04-27T19:46:22.219449Z","end":"2026-04-27T19:46:22.469103Z","steps":["trace[279841927] 'agreement among raft nodes before linearized reading' (duration: 249.505573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:22.469145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.009366ms","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-04-27T19:46:22.469213Z","caller":"traceutil/trace.go:171","msg":"trace[827439606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1256; }","duration":"239.103118ms","start":"2026-04-27T19:46:22.230097Z","end":"2026-04-27T19:46:22.4692Z","steps":["trace[827439606] 'agreement among raft nodes before linearized reading' (duration: 238.949314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:22.469229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.503928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:46:22.469276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.502474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-27T19:46:22.469278Z","caller":"traceutil/trace.go:171","msg":"trace[968833788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1256; }","duration":"215.58599ms","start":"2026-04-27T19:46:22.253678Z","end":"2026-04-27T19:46:22.469264Z","steps":["trace[968833788] 'agreement among raft nodes before linearized reading' (duration: 215.527739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:22.469129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.022606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T19:46:22.46931Z","caller":"traceutil/trace.go:171","msg":"trace[1246313693] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1256; }","duration":"240.567886ms","start":"2026-04-27T19:46:22.228734Z","end":"2026-04-27T19:46:22.469302Z","steps":["trace[1246313693] 'agreement among raft nodes before linearized reading' (duration: 240.485014ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:22.469353Z","caller":"traceutil/trace.go:171","msg":"trace[1849826223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1256; }","duration":"226.291103ms","start":"2026-04-27T19:46:22.243048Z","end":"2026-04-27T19:46:22.469339Z","steps":["trace[1849826223] 'agreement among raft nodes before linearized reading' (duration: 225.919374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:22.469877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.497015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-27T19:46:22.469936Z","caller":"traceutil/trace.go:171","msg":"trace[756914087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1256; }","duration":"255.555767ms","start":"2026-04-27T19:46:22.214364Z","end":"2026-04-27T19:46:22.46992Z","steps":["trace[756914087] 'agreement among raft nodes before linearized reading' (duration: 255.368792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:26.32508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.595346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:46:26.325181Z","caller":"traceutil/trace.go:171","msg":"trace[131300339] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1333; }","duration":"1.071027789s","start":"2026-04-27T19:46:25.254138Z","end":"2026-04-27T19:46:26.325166Z","steps":["trace[131300339] 'read index received' (duration: 312.147565ms)","trace[131300339] 'applied index is now lower than readState.Index' (duration: 758.879074ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:46:26.325223Z","caller":"traceutil/trace.go:171","msg":"trace[1137696762] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"1.134306318s","start":"2026-04-27T19:46:25.190895Z","end":"2026-04-27T19:46:26.325201Z","steps":["trace[1137696762] 'process raft request' (duration: 375.491626ms)","trace[1137696762] 'compare' (duration: 758.396821ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:46:26.325299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071159962s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:46:26.325311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:25.190883Z","time spent":"1.134384259s","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:46:26.325345Z","caller":"traceutil/trace.go:171","msg":"trace[1224683017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1277; }","duration":"1.071243484s","start":"2026-04-27T19:46:25.254087Z","end":"2026-04-27T19:46:26.325331Z","steps":["trace[1224683017] 'agreement among raft nodes before linearized reading' (duration: 1.071133631s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:26.32538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:25.254068Z","time spent":"1.071303976s","remote":"127.0.0.1:60966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T19:46:26.519319Z","caller":"traceutil/trace.go:171","msg":"trace[1179775012] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1334; }","duration":"194.087285ms","start":"2026-04-27T19:46:26.325204Z","end":"2026-04-27T19:46:26.519291Z","steps":["trace[1179775012] 'read index received' (duration: 165.850867ms)","trace[1179775012] 'applied index is now lower than readState.Index' (duration: 28.235278ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:46:26.519709Z","caller":"traceutil/trace.go:171","msg":"trace[31873619] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"217.180822ms","start":"2026-04-27T19:46:26.302511Z","end":"2026-04-27T19:46:26.519692Z","steps":["trace[31873619] 'process raft request' (duration: 188.544744ms)","trace[31873619] 'compare' (duration: 27.998052ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:46:26.519782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.410297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:46:26.519859Z","caller":"traceutil/trace.go:171","msg":"trace[808024620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1278; }","duration":"193.5321ms","start":"2026-04-27T19:46:26.32631Z","end":"2026-04-27T19:46:26.519842Z","steps":["trace[808024620] 'agreement among raft nodes before linearized reading' (duration: 193.407917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:26.519905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.993447ms","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-04-27T19:46:26.519999Z","caller":"traceutil/trace.go:171","msg":"trace[320441162] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1278; }","duration":"337.168272ms","start":"2026-04-27T19:46:26.182808Z","end":"2026-04-27T19:46:26.519977Z","steps":["trace[320441162] 'agreement among raft nodes before linearized reading' (duration: 336.969887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:26.52006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:26.18279Z","time spent":"337.255814ms","remote":"127.0.0.1:33076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:46:26.520077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.483544ms","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-04-27T19:46:26.520115Z","caller":"traceutil/trace.go:171","msg":"trace[557893331] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1278; }","duration":"192.550365ms","start":"2026-04-27T19:46:26.327554Z","end":"2026-04-27T19:46:26.520104Z","steps":["trace[557893331] 'agreement among raft nodes before linearized reading' (duration: 192.464283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:26.520232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.6648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T19:46:26.520281Z","caller":"traceutil/trace.go:171","msg":"trace[765339234] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1278; }","duration":"113.713141ms","start":"2026-04-27T19:46:26.406551Z","end":"2026-04-27T19:46:26.520265Z","steps":["trace[765339234] 'agreement among raft nodes before linearized reading' (duration: 113.639589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:26.520338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.052625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:46:26.520392Z","caller":"traceutil/trace.go:171","msg":"trace[373574601] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1278; }","duration":"259.137338ms","start":"2026-04-27T19:46:26.26124Z","end":"2026-04-27T19:46:26.520378Z","steps":["trace[373574601] 'agreement among raft nodes before linearized reading' (duration: 259.051696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:26.826769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.827261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:46:26.826986Z","caller":"traceutil/trace.go:171","msg":"trace[2006740418] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"242.100695ms","start":"2026-04-27T19:46:26.584869Z","end":"2026-04-27T19:46:26.82697Z","steps":["trace[2006740418] 'process raft request' (duration: 242.023803ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:26.82698Z","caller":"traceutil/trace.go:171","msg":"trace[1478912860] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"243.029737ms","start":"2026-04-27T19:46:26.583928Z","end":"2026-04-27T19:46:26.826958Z","steps":["trace[1478912860] 'process raft request' (duration: 242.924015ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:26.827024Z","caller":"traceutil/trace.go:171","msg":"trace[1154840564] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"244.148066ms","start":"2026-04-27T19:46:26.582849Z","end":"2026-04-27T19:46:26.826997Z","steps":["trace[1154840564] 'process raft request' (duration: 63.998617ms)","trace[1154840564] 'compare' (duration: 179.638537ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:46:26.827085Z","caller":"traceutil/trace.go:171","msg":"trace[980928640] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"241.653633ms","start":"2026-04-27T19:46:26.585418Z","end":"2026-04-27T19:46:26.827072Z","steps":["trace[980928640] 'process raft request' (duration: 241.499549ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:26.827576Z","caller":"traceutil/trace.go:171","msg":"trace[1966092759] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"241.442129ms","start":"2026-04-27T19:46:26.585953Z","end":"2026-04-27T19:46:26.827395Z","steps":["trace[1966092759] 'process raft request' (duration: 240.984407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:28.188005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.8126ms","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-04-27T19:46:28.188098Z","caller":"traceutil/trace.go:171","msg":"trace[267448337] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1285; }","duration":"760.967714ms","start":"2026-04-27T19:46:27.427112Z","end":"2026-04-27T19:46:28.18808Z","steps":["trace[267448337] 'count revisions from in-memory index tree' (duration: 760.733658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:28.188098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.235886ms","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":"warn","ts":"2026-04-27T19:46:28.188145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:27.427094Z","time spent":"761.039206ms","remote":"127.0.0.1:51682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-04-27T19:46:28.188159Z","caller":"traceutil/trace.go:171","msg":"trace[553417253] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1285; }","duration":"140.333557ms","start":"2026-04-27T19:46:28.047811Z","end":"2026-04-27T19:46:28.188145Z","steps":["trace[553417253] 'count revisions from in-memory index tree' (duration: 140.126052ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:29.002788Z","caller":"traceutil/trace.go:171","msg":"trace[1606578643] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"171.799088ms","start":"2026-04-27T19:46:28.830963Z","end":"2026-04-27T19:46:29.002762Z","steps":["trace[1606578643] 'read index received' (duration: 171.567882ms)","trace[1606578643] 'applied index is now lower than readState.Index' (duration: 229.816µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:46:29.003076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.092275ms","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-04-27T19:46:29.002919Z","caller":"traceutil/trace.go:171","msg":"trace[1148573549] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"410.892726ms","start":"2026-04-27T19:46:28.591992Z","end":"2026-04-27T19:46:29.002884Z","steps":["trace[1148573549] 'process raft request' (duration: 410.565628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:29.003119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.931861ms","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-04-27T19:46:29.003146Z","caller":"traceutil/trace.go:171","msg":"trace[437808613] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1286; }","duration":"172.200798ms","start":"2026-04-27T19:46:28.830928Z","end":"2026-04-27T19:46:29.003129Z","steps":["trace[437808613] 'agreement among raft nodes before linearized reading' (duration: 171.966032ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:29.003157Z","caller":"traceutil/trace.go:171","msg":"trace[1175060637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1286; }","duration":"171.970632ms","start":"2026-04-27T19:46:28.831177Z","end":"2026-04-27T19:46:29.003148Z","steps":["trace[1175060637] 'agreement among raft nodes before linearized reading' (duration: 171.88983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:29.003119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.637648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-27T19:46:29.003139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.978832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-27T19:46:29.003232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.071894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T19:46:29.003266Z","caller":"traceutil/trace.go:171","msg":"trace[1227259016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1286; }","duration":"172.148896ms","start":"2026-04-27T19:46:28.831108Z","end":"2026-04-27T19:46:29.003257Z","steps":["trace[1227259016] 'agreement among raft nodes before linearized reading' (duration: 171.855599ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:29.003278Z","caller":"traceutil/trace.go:171","msg":"trace[1750974640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1286; }","duration":"172.125166ms","start":"2026-04-27T19:46:28.831139Z","end":"2026-04-27T19:46:29.003265Z","steps":["trace[1750974640] 'agreement among raft nodes before linearized reading' (duration: 172.033374ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:29.003219Z","caller":"traceutil/trace.go:171","msg":"trace[74344235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1286; }","duration":"170.758162ms","start":"2026-04-27T19:46:28.832454Z","end":"2026-04-27T19:46:29.003212Z","steps":["trace[74344235] 'agreement among raft nodes before linearized reading' (duration: 170.541737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:29.003533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:28.591973Z","time spent":"411.42519ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:46:29.370881Z","caller":"traceutil/trace.go:171","msg":"trace[124952996] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"361.292435ms","start":"2026-04-27T19:46:29.009541Z","end":"2026-04-27T19:46:29.370833Z","steps":["trace[124952996] 'process raft request' (duration: 361.253284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:29.37131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:29.009534Z","time spent":"361.51939ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:46:29.371564Z","caller":"traceutil/trace.go:171","msg":"trace[335807649] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"362.155107ms","start":"2026-04-27T19:46:29.009397Z","end":"2026-04-27T19:46:29.371552Z","steps":["trace[335807649] 'process raft request' (duration: 361.302764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:29.371624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:29.009385Z","time spent":"362.210569ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:46:29.371762Z","caller":"traceutil/trace.go:171","msg":"trace[1740291728] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"362.26413ms","start":"2026-04-27T19:46:29.009488Z","end":"2026-04-27T19:46:29.371752Z","steps":["trace[1740291728] 'process raft request' (duration: 361.265214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:29.37182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:29.009481Z","time spent":"362.312301ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:46:29.372401Z","caller":"traceutil/trace.go:171","msg":"trace[1512348289] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"362.507837ms","start":"2026-04-27T19:46:29.008918Z","end":"2026-04-27T19:46:29.371426Z","steps":["trace[1512348289] 'process raft request' (duration: 361.585753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:29.372573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:29.00889Z","time spent":"363.597084ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:46:30.024747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.212719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:46:30.02483Z","caller":"traceutil/trace.go:171","msg":"trace[512164508] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1291; }","duration":"589.350222ms","start":"2026-04-27T19:46:29.435463Z","end":"2026-04-27T19:46:30.024813Z","steps":["trace[512164508] 'count revisions from in-memory index tree' (duration: 589.114267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:30.024872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:29.435434Z","time spent":"589.427325ms","remote":"127.0.0.1:56646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:46:30.024753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.80889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:46:30.025025Z","caller":"traceutil/trace.go:171","msg":"trace[233339481] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1291; }","duration":"285.136299ms","start":"2026-04-27T19:46:29.73984Z","end":"2026-04-27T19:46:30.024976Z","steps":["trace[233339481] 'count revisions from in-memory index tree' (duration: 284.709438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:30.653057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.671978ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-27T19:46:31.302335Z","caller":"traceutil/trace.go:171","msg":"trace[932012613] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1349; }","duration":"262.480563ms","start":"2026-04-27T19:46:31.039824Z","end":"2026-04-27T19:46:31.302305Z","steps":["trace[932012613] 'read index received' (duration: 262.127424ms)","trace[932012613] 'applied index is now lower than readState.Index' (duration: 351.629µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:46:31.30244Z","caller":"traceutil/trace.go:171","msg":"trace[725442802] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"293.755108ms","start":"2026-04-27T19:46:31.008665Z","end":"2026-04-27T19:46:31.30242Z","steps":["trace[725442802] 'process raft request' (duration: 293.40839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:31.302658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.793771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-27T19:46:31.302705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.895712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:46:31.302711Z","caller":"traceutil/trace.go:171","msg":"trace[741715860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1292; }","duration":"262.915634ms","start":"2026-04-27T19:46:31.039775Z","end":"2026-04-27T19:46:31.302691Z","steps":["trace[741715860] 'agreement among raft nodes before linearized reading' (duration: 262.717339ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:31.30274Z","caller":"traceutil/trace.go:171","msg":"trace[2102986052] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1292; }","duration":"166.998075ms","start":"2026-04-27T19:46:31.135731Z","end":"2026-04-27T19:46:31.302729Z","steps":["trace[2102986052] 'agreement among raft nodes before linearized reading' (duration: 166.917883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:31.829546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.394906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:46:31.829651Z","caller":"traceutil/trace.go:171","msg":"trace[1864805911] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1350; }","duration":"452.962726ms","start":"2026-04-27T19:46:31.376675Z","end":"2026-04-27T19:46:31.829637Z","steps":["trace[1864805911] 'read index received' (duration: 320.384166ms)","trace[1864805911] 'applied index is now lower than readState.Index' (duration: 132.57751ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:46:31.829879Z","caller":"traceutil/trace.go:171","msg":"trace[1539064074] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"521.450748ms","start":"2026-04-27T19:46:31.308416Z","end":"2026-04-27T19:46:31.829867Z","steps":["trace[1539064074] 'process raft request' (duration: 388.668982ms)","trace[1539064074] 'compare' (duration: 132.015526ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:46:31.829936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:31.308396Z","time spent":"521.511189ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:46:31.830169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.491839ms","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-04-27T19:46:31.830198Z","caller":"traceutil/trace.go:171","msg":"trace[645252436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1293; }","duration":"453.554121ms","start":"2026-04-27T19:46:31.376634Z","end":"2026-04-27T19:46:31.830188Z","steps":["trace[645252436] 'agreement among raft nodes before linearized reading' (duration: 453.452218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:31.830226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:31.376614Z","time spent":"453.603641ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T19:46:31.830385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.709155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-27T19:46:31.830409Z","caller":"traceutil/trace.go:171","msg":"trace[1545202351] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1293; }","duration":"453.762866ms","start":"2026-04-27T19:46:31.376638Z","end":"2026-04-27T19:46:31.830401Z","steps":["trace[1545202351] 'agreement among raft nodes before linearized reading' (duration: 453.692503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:31.830433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:31.376626Z","time spent":"453.801728ms","remote":"127.0.0.1:33020","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-04-27T19:46:31.830591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.407498ms","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-04-27T19:46:31.830654Z","caller":"traceutil/trace.go:171","msg":"trace[633194219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1293; }","duration":"453.486089ms","start":"2026-04-27T19:46:31.377159Z","end":"2026-04-27T19:46:31.830645Z","steps":["trace[633194219] 'agreement among raft nodes before linearized reading' (duration: 453.381466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:31.83068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:31.377151Z","time spent":"453.522521ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:46:31.830926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.53493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:46:31.830957Z","caller":"traceutil/trace.go:171","msg":"trace[1555271370] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1293; }","duration":"309.571981ms","start":"2026-04-27T19:46:31.521376Z","end":"2026-04-27T19:46:31.830948Z","steps":["trace[1555271370] 'agreement among raft nodes before linearized reading' (duration: 309.519659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:31.830987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:31.521307Z","time spent":"309.671713ms","remote":"127.0.0.1:56580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:46:31.831153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.189642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T19:46:31.831177Z","caller":"traceutil/trace.go:171","msg":"trace[1514965417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1293; }","duration":"453.230493ms","start":"2026-04-27T19:46:31.377939Z","end":"2026-04-27T19:46:31.831169Z","steps":["trace[1514965417] 'agreement among raft nodes before linearized reading' (duration: 453.159811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:31.831201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:31.377931Z","time spent":"453.263384ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-27T19:46:32.328979Z","caller":"traceutil/trace.go:171","msg":"trace[1294332168] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1352; }","duration":"419.971577ms","start":"2026-04-27T19:46:31.908984Z","end":"2026-04-27T19:46:32.328955Z","steps":["trace[1294332168] 'read index received' (duration: 419.490095ms)","trace[1294332168] 'applied index is now lower than readState.Index' (duration: 480.632µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:46:32.329131Z","caller":"traceutil/trace.go:171","msg":"trace[1269641381] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"493.013535ms","start":"2026-04-27T19:46:31.836048Z","end":"2026-04-27T19:46:32.329061Z","steps":["trace[1269641381] 'process raft request' (duration: 492.546883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:32.329303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.411192ms","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-04-27T19:46:32.329349Z","caller":"traceutil/trace.go:171","msg":"trace[475743376] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1297; }","duration":"321.508215ms","start":"2026-04-27T19:46:32.007834Z","end":"2026-04-27T19:46:32.329342Z","steps":["trace[475743376] 'agreement among raft nodes before linearized reading' (duration: 321.406462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:32.329378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:32.007819Z","time spent":"321.551355ms","remote":"127.0.0.1:56674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:46:32.329455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.40568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:46:32.329528Z","caller":"traceutil/trace.go:171","msg":"trace[787085655] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1297; }","duration":"420.556493ms","start":"2026-04-27T19:46:31.908959Z","end":"2026-04-27T19:46:32.329516Z","steps":["trace[787085655] 'agreement among raft nodes before linearized reading' (duration: 420.243425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:32.329591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:31.908948Z","time spent":"420.629824ms","remote":"127.0.0.1:33034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-27T19:46:32.329192Z","caller":"traceutil/trace.go:171","msg":"trace[1130329032] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"492.457711ms","start":"2026-04-27T19:46:31.836723Z","end":"2026-04-27T19:46:32.329181Z","steps":["trace[1130329032] 'process raft request' (duration: 492.193904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:32.329528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:31.836039Z","time spent":"493.298842ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:46:32.329993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:31.836715Z","time spent":"493.18841ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:46:32.329134Z","caller":"traceutil/trace.go:171","msg":"trace[1425752658] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"493.008505ms","start":"2026-04-27T19:46:31.836097Z","end":"2026-04-27T19:46:32.329106Z","steps":["trace[1425752658] 'process raft request' (duration: 492.762947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:32.330126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:31.836093Z","time spent":"493.98539ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:46:32.506691Z","caller":"traceutil/trace.go:171","msg":"trace[201728570] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"171.468419ms","start":"2026-04-27T19:46:32.335195Z","end":"2026-04-27T19:46:32.506664Z","steps":["trace[201728570] 'process raft request' (duration: 87.374591ms)","trace[201728570] 'compare' (duration: 83.635767ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:46:35.754573Z","caller":"traceutil/trace.go:171","msg":"trace[1877779622] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"440.619243ms","start":"2026-04-27T19:46:35.313934Z","end":"2026-04-27T19:46:35.754553Z","steps":["trace[1877779622] 'process raft request' (duration: 440.49508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:35.754732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:35.313914Z","time spent":"440.743947ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:46:35.755048Z","caller":"traceutil/trace.go:171","msg":"trace[1666528117] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1390; }","duration":"141.930108ms","start":"2026-04-27T19:46:35.613088Z","end":"2026-04-27T19:46:35.755018Z","steps":["trace[1666528117] 'read index received' (duration: 141.920408ms)","trace[1666528117] 'applied index is now lower than readState.Index' (duration: 7.92µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:46:35.776555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.380264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:46:35.776626Z","caller":"traceutil/trace.go:171","msg":"trace[714628932] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1331; }","duration":"163.564509ms","start":"2026-04-27T19:46:35.613042Z","end":"2026-04-27T19:46:35.776606Z","steps":["trace[714628932] 'agreement among raft nodes before linearized reading' (duration: 142.080073ms)","trace[714628932] 'count revisions from in-memory index tree' (duration: 21.28841ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:46:36.078183Z","caller":"traceutil/trace.go:171","msg":"trace[476464036] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1390; }","duration":"159.214018ms","start":"2026-04-27T19:46:35.918945Z","end":"2026-04-27T19:46:36.07816Z","steps":["trace[476464036] 'read index received' (duration: 159.020153ms)","trace[476464036] 'applied index is now lower than readState.Index' (duration: 192.685µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:46:36.078279Z","caller":"traceutil/trace.go:171","msg":"trace[448756422] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"163.388173ms","start":"2026-04-27T19:46:35.914855Z","end":"2026-04-27T19:46:36.078243Z","steps":["trace[448756422] 'process raft request' (duration: 163.134287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:36.078376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.418503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-27T19:46:36.078424Z","caller":"traceutil/trace.go:171","msg":"trace[246235122] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1332; }","duration":"159.509535ms","start":"2026-04-27T19:46:35.918891Z","end":"2026-04-27T19:46:36.078401Z","steps":["trace[246235122] 'agreement among raft nodes before linearized reading' (duration: 159.360921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:36.535617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.099482ms","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-04-27T19:46:36.535696Z","caller":"traceutil/trace.go:171","msg":"trace[2076353131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1333; }","duration":"105.232735ms","start":"2026-04-27T19:46:36.430446Z","end":"2026-04-27T19:46:36.535679Z","steps":["trace[2076353131] 'range keys from in-memory index tree' (duration: 104.913507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:36.535943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.439321ms","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-04-27T19:46:36.53607Z","caller":"traceutil/trace.go:171","msg":"trace[341668419] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1333; }","duration":"107.601945ms","start":"2026-04-27T19:46:36.42845Z","end":"2026-04-27T19:46:36.536052Z","steps":["trace[341668419] 'range keys from in-memory index tree' (duration: 107.247146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:36.53598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.617149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T19:46:36.536424Z","caller":"traceutil/trace.go:171","msg":"trace[710148638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1333; }","duration":"116.06102ms","start":"2026-04-27T19:46:36.420347Z","end":"2026-04-27T19:46:36.536408Z","steps":["trace[710148638] 'range keys from in-memory index tree' (duration: 115.481466ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:37.14354Z","caller":"traceutil/trace.go:171","msg":"trace[2034564840] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"149.292805ms","start":"2026-04-27T19:46:36.994224Z","end":"2026-04-27T19:46:37.143517Z","steps":["trace[2034564840] 'process raft request' (duration: 149.10825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:37.760232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.898504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:46:37.760304Z","caller":"traceutil/trace.go:171","msg":"trace[1873260883] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1339; }","duration":"110.020467ms","start":"2026-04-27T19:46:37.650268Z","end":"2026-04-27T19:46:37.760288Z","steps":["trace[1873260883] 'range keys from in-memory index tree' (duration: 109.830102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:37.760331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.639333ms","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-04-27T19:46:37.7604Z","caller":"traceutil/trace.go:171","msg":"trace[2142509688] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1339; }","duration":"123.744296ms","start":"2026-04-27T19:46:37.636632Z","end":"2026-04-27T19:46:37.760377Z","steps":["trace[2142509688] 'range keys from in-memory index tree' (duration: 123.463188ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:37.900647Z","caller":"traceutil/trace.go:171","msg":"trace[1860860387] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"136.442398ms","start":"2026-04-27T19:46:37.764174Z","end":"2026-04-27T19:46:37.900617Z","steps":["trace[1860860387] 'process raft request' (duration: 108.396975ms)","trace[1860860387] 'compare' (duration: 27.836497ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:46:37.900705Z","caller":"traceutil/trace.go:171","msg":"trace[470282470] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"136.497829ms","start":"2026-04-27T19:46:37.764189Z","end":"2026-04-27T19:46:37.900686Z","steps":["trace[470282470] 'process raft request' (duration: 136.369346ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:37.900995Z","caller":"traceutil/trace.go:171","msg":"trace[1442674242] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"136.660575ms","start":"2026-04-27T19:46:37.764326Z","end":"2026-04-27T19:46:37.900987Z","steps":["trace[1442674242] 'process raft request' (duration: 136.305055ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:38.195668Z","caller":"traceutil/trace.go:171","msg":"trace[2044179372] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"110.209773ms","start":"2026-04-27T19:46:38.085433Z","end":"2026-04-27T19:46:38.195643Z","steps":["trace[2044179372] 'process raft request' (duration: 109.864214ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:38.345149Z","caller":"traceutil/trace.go:171","msg":"trace[875366959] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"145.53843ms","start":"2026-04-27T19:46:38.199588Z","end":"2026-04-27T19:46:38.345126Z","steps":["trace[875366959] 'process raft request' (duration: 110.339034ms)","trace[875366959] 'compare' (duration: 35.109093ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:46:38.732383Z","caller":"traceutil/trace.go:171","msg":"trace[2428634] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"124.581147ms","start":"2026-04-27T19:46:38.607775Z","end":"2026-04-27T19:46:38.732356Z","steps":["trace[2428634] 'process raft request' (duration: 81.145583ms)","trace[2428634] 'compare' (duration: 43.318161ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:46:40.440914Z","caller":"traceutil/trace.go:171","msg":"trace[119740030] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"852.987134ms","start":"2026-04-27T19:46:39.587903Z","end":"2026-04-27T19:46:40.440891Z","steps":["trace[119740030] 'process raft request' (duration: 852.83609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:40.441086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:39.587879Z","time spent":"853.124237ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:46:41.766231Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.325476039s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T19:46:41.766951Z","caller":"traceutil/trace.go:171","msg":"trace[894706033] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1410; }","duration":"1.719752353s","start":"2026-04-27T19:46:40.047173Z","end":"2026-04-27T19:46:41.766925Z","steps":["trace[894706033] 'read index received' (duration: 393.611906ms)","trace[894706033] 'applied index is now lower than readState.Index' (duration: 1.326138547s)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:46:41.767086Z","caller":"traceutil/trace.go:171","msg":"trace[2089106886] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"1.973089824s","start":"2026-04-27T19:46:39.79398Z","end":"2026-04-27T19:46:41.76707Z","steps":["trace[2089106886] 'process raft request' (duration: 1.972500068s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:41.76719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:39.793963Z","time spent":"1.973153865s","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:46:41.767181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.719989649s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T19:46:41.767335Z","caller":"traceutil/trace.go:171","msg":"trace[547481530] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1353; }","duration":"1.720173754s","start":"2026-04-27T19:46:40.047125Z","end":"2026-04-27T19:46:41.767299Z","steps":["trace[547481530] 'agreement among raft nodes before linearized reading' (duration: 1.719977679s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:41.767379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:40.047014Z","time spent":"1.720353628s","remote":"127.0.0.1:32932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:46:41.770928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.570771426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-27T19:46:41.771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164518345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-27T19:46:41.771049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.517290457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:46:41.771076Z","caller":"traceutil/trace.go:171","msg":"trace[921853881] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1353; }","duration":"1.164591447s","start":"2026-04-27T19:46:40.606434Z","end":"2026-04-27T19:46:41.771026Z","steps":["trace[921853881] 'agreement among raft nodes before linearized reading' (duration: 1.164476124s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:41.77109Z","caller":"traceutil/trace.go:171","msg":"trace[1801591071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1353; }","duration":"1.517366299s","start":"2026-04-27T19:46:40.253714Z","end":"2026-04-27T19:46:41.77108Z","steps":["trace[1801591071] 'agreement among raft nodes before linearized reading' (duration: 1.517307117s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:41.771113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.422544988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-27T19:46:41.771128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:40.253694Z","time spent":"1.51742591s","remote":"127.0.0.1:60966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T19:46:41.770997Z","caller":"traceutil/trace.go:171","msg":"trace[845280457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1353; }","duration":"1.570883449s","start":"2026-04-27T19:46:40.200095Z","end":"2026-04-27T19:46:41.770979Z","steps":["trace[845280457] 'agreement among raft nodes before linearized reading' (duration: 1.570694684s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:46:41.771181Z","caller":"traceutil/trace.go:171","msg":"trace[134964008] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1353; }","duration":"1.422659351s","start":"2026-04-27T19:46:40.348505Z","end":"2026-04-27T19:46:41.771165Z","steps":["trace[134964008] 'agreement among raft nodes before linearized reading' (duration: 1.422498037s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:41.771183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034203682s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-27T19:46:41.77122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:40.348485Z","time spent":"1.422725292s","remote":"127.0.0.1:33020","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-04-27T19:46:41.771229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:40.200074Z","time spent":"1.571130425s","remote":"127.0.0.1:33020","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-04-27T19:46:41.771278Z","caller":"traceutil/trace.go:171","msg":"trace[1809096516] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1353; }","duration":"1.034361987s","start":"2026-04-27T19:46:40.736893Z","end":"2026-04-27T19:46:41.771255Z","steps":["trace[1809096516] 'agreement among raft nodes before linearized reading' (duration: 1.03408431s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:41.771197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170910478s","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-04-27T19:46:41.77135Z","caller":"traceutil/trace.go:171","msg":"trace[499164695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1353; }","duration":"1.171117023s","start":"2026-04-27T19:46:40.60022Z","end":"2026-04-27T19:46:41.771337Z","steps":["trace[499164695] 'agreement among raft nodes before linearized reading' (duration: 1.170821366s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:41.771356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:40.736868Z","time spent":"1.034472139s","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T19:46:41.771399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:40.600201Z","time spent":"1.171185895s","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T19:46:41.771117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:40.606414Z","time spent":"1.16469385s","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:46:41.771562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.450981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:46:41.771998Z","caller":"traceutil/trace.go:171","msg":"trace[925370233] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1353; }","duration":"433.904122ms","start":"2026-04-27T19:46:41.338058Z","end":"2026-04-27T19:46:41.771962Z","steps":["trace[925370233] 'agreement among raft nodes before linearized reading' (duration: 432.878936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:46:41.772141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:46:41.338038Z","time spent":"433.996555ms","remote":"127.0.0.1:33060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-04-27T19:46:42.680123Z","caller":"traceutil/trace.go:171","msg":"trace[283539258] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"115.958048ms","start":"2026-04-27T19:46:42.564132Z","end":"2026-04-27T19:46:42.68009Z","steps":["trace[283539258] 'process raft request' (duration: 115.765034ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:20.059591Z","caller":"traceutil/trace.go:171","msg":"trace[1307375474] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"102.150997ms","start":"2026-04-27T19:47:19.957393Z","end":"2026-04-27T19:47:20.059544Z","steps":["trace[1307375474] 'process raft request' (duration: 101.971573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:20.338432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.955678ms","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-04-27T19:47:20.33851Z","caller":"traceutil/trace.go:171","msg":"trace[1662270250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1548; }","duration":"153.075811ms","start":"2026-04-27T19:47:20.185416Z","end":"2026-04-27T19:47:20.338492Z","steps":["trace[1662270250] 'range keys from in-memory index tree' (duration: 152.801234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:20.338538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.22574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-27T19:47:20.338586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.013579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T19:47:20.338669Z","caller":"traceutil/trace.go:171","msg":"trace[1996299458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1548; }","duration":"153.111341ms","start":"2026-04-27T19:47:20.18554Z","end":"2026-04-27T19:47:20.338652Z","steps":["trace[1996299458] 'range keys from in-memory index tree' (duration: 152.910257ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:20.338688Z","caller":"traceutil/trace.go:171","msg":"trace[646976265] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1548; }","duration":"187.372634ms","start":"2026-04-27T19:47:20.151272Z","end":"2026-04-27T19:47:20.338645Z","steps":["trace[646976265] 'range keys from in-memory index tree' (duration: 187.029996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:20.338538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.364145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-27T19:47:20.338895Z","caller":"traceutil/trace.go:171","msg":"trace[624209636] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1548; }","duration":"154.750584ms","start":"2026-04-27T19:47:20.184077Z","end":"2026-04-27T19:47:20.338828Z","steps":["trace[624209636] 'range keys from in-memory index tree' (duration: 154.18774ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:20.547903Z","caller":"traceutil/trace.go:171","msg":"trace[2047944354] transaction","detail":"{read_only:false; response_revision:1551; number_of_response:1; }","duration":"202.325773ms","start":"2026-04-27T19:47:20.345556Z","end":"2026-04-27T19:47:20.547882Z","steps":["trace[2047944354] 'process raft request' (duration: 202.269891ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:20.54837Z","caller":"traceutil/trace.go:171","msg":"trace[1346370402] transaction","detail":"{read_only:false; response_revision:1552; number_of_response:1; }","duration":"200.9793ms","start":"2026-04-27T19:47:20.347378Z","end":"2026-04-27T19:47:20.548357Z","steps":["trace[1346370402] 'process raft request' (duration: 200.479067ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:20.547905Z","caller":"traceutil/trace.go:171","msg":"trace[1601569781] transaction","detail":"{read_only:false; response_revision:1550; number_of_response:1; }","duration":"204.229722ms","start":"2026-04-27T19:47:20.343653Z","end":"2026-04-27T19:47:20.547883Z","steps":["trace[1601569781] 'process raft request' (duration: 204.051347ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:22.65357Z","caller":"traceutil/trace.go:171","msg":"trace[625576156] linearizableReadLoop","detail":"{readStateIndex:1625; appliedIndex:1624; }","duration":"100.559657ms","start":"2026-04-27T19:47:22.552992Z","end":"2026-04-27T19:47:22.653552Z","steps":["trace[625576156] 'read index received' (duration: 100.421703ms)","trace[625576156] 'applied index is now lower than readState.Index' (duration: 137.084µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:47:22.653665Z","caller":"traceutil/trace.go:171","msg":"trace[1800395839] transaction","detail":"{read_only:false; response_revision:1557; number_of_response:1; }","duration":"256.738375ms","start":"2026-04-27T19:47:22.396916Z","end":"2026-04-27T19:47:22.653655Z","steps":["trace[1800395839] 'process raft request' (duration: 256.491809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:22.65385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.844144ms","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-04-27T19:47:22.653887Z","caller":"traceutil/trace.go:171","msg":"trace[74823602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1557; }","duration":"100.928636ms","start":"2026-04-27T19:47:22.55295Z","end":"2026-04-27T19:47:22.653879Z","steps":["trace[74823602] 'agreement among raft nodes before linearized reading' (duration: 100.809303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:22.654098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.985387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-27T19:47:22.654175Z","caller":"traceutil/trace.go:171","msg":"trace[1137419012] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1557; }","duration":"101.11563ms","start":"2026-04-27T19:47:22.553041Z","end":"2026-04-27T19:47:22.654156Z","steps":["trace[1137419012] 'agreement among raft nodes before linearized reading' (duration: 100.936545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:22.654201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.772132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-27T19:47:22.654228Z","caller":"traceutil/trace.go:171","msg":"trace[1970310402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1557; }","duration":"100.820703ms","start":"2026-04-27T19:47:22.553401Z","end":"2026-04-27T19:47:22.654222Z","steps":["trace[1970310402] 'agreement among raft nodes before linearized reading' (duration: 100.756941ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:22.837778Z","caller":"traceutil/trace.go:171","msg":"trace[735799822] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"180.619525ms","start":"2026-04-27T19:47:22.657122Z","end":"2026-04-27T19:47:22.837741Z","steps":["trace[735799822] 'process raft request' (duration: 100.126445ms)","trace[735799822] 'compare' (duration: 79.640898ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:47:22.837792Z","caller":"traceutil/trace.go:171","msg":"trace[2024321032] transaction","detail":"{read_only:false; response_revision:1560; number_of_response:1; }","duration":"180.147053ms","start":"2026-04-27T19:47:22.65762Z","end":"2026-04-27T19:47:22.837767Z","steps":["trace[2024321032] 'process raft request' (duration: 179.706902ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:25.698697Z","caller":"traceutil/trace.go:171","msg":"trace[1599302347] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"135.828716ms","start":"2026-04-27T19:47:25.562842Z","end":"2026-04-27T19:47:25.698671Z","steps":["trace[1599302347] 'process raft request' (duration: 135.59237ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:26.253067Z","caller":"traceutil/trace.go:171","msg":"trace[1511510414] transaction","detail":"{read_only:false; response_revision:1575; number_of_response:1; }","duration":"104.882186ms","start":"2026-04-27T19:47:26.148155Z","end":"2026-04-27T19:47:26.253037Z","steps":["trace[1511510414] 'process raft request' (duration: 104.539466ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:27.308634Z","caller":"traceutil/trace.go:171","msg":"trace[1747978591] transaction","detail":"{read_only:false; response_revision:1581; number_of_response:1; }","duration":"150.209708ms","start":"2026-04-27T19:47:27.158398Z","end":"2026-04-27T19:47:27.308607Z","steps":["trace[1747978591] 'process raft request' (duration: 149.990063ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:35.159016Z","caller":"traceutil/trace.go:171","msg":"trace[1022678499] transaction","detail":"{read_only:false; response_revision:1617; number_of_response:1; }","duration":"120.53112ms","start":"2026-04-27T19:47:35.038459Z","end":"2026-04-27T19:47:35.15899Z","steps":["trace[1022678499] 'process raft request' (duration: 115.064212ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:36.458199Z","caller":"traceutil/trace.go:171","msg":"trace[690808731] transaction","detail":"{read_only:false; response_revision:1622; number_of_response:1; }","duration":"104.778012ms","start":"2026-04-27T19:47:36.353387Z","end":"2026-04-27T19:47:36.458165Z","steps":["trace[690808731] 'process raft request' (duration: 99.576632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:37.099315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.393872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:47:37.099624Z","caller":"traceutil/trace.go:171","msg":"trace[797072669] transaction","detail":"{read_only:false; response_revision:1624; number_of_response:1; }","duration":"187.359785ms","start":"2026-04-27T19:47:36.912235Z","end":"2026-04-27T19:47:37.099595Z","steps":["trace[797072669] 'process raft request' (duration: 59.585853ms)","trace[797072669] 'compare' (duration: 127.251489ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:47:37.318138Z","caller":"traceutil/trace.go:171","msg":"trace[227575745] transaction","detail":"{read_only:false; response_revision:1625; number_of_response:1; }","duration":"214.040178ms","start":"2026-04-27T19:47:37.10407Z","end":"2026-04-27T19:47:37.31811Z","steps":["trace[227575745] 'process raft request' (duration: 115.805841ms)","trace[227575745] 'compare' (duration: 97.780146ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:47:37.318252Z","caller":"traceutil/trace.go:171","msg":"trace[1359025725] linearizableReadLoop","detail":"{readStateIndex:1696; appliedIndex:1695; }","duration":"154.972998ms","start":"2026-04-27T19:47:37.162819Z","end":"2026-04-27T19:47:37.317792Z","steps":["trace[1359025725] 'read index received' (duration: 57.069529ms)","trace[1359025725] 'applied index is now lower than readState.Index' (duration: 97.901989ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:47:37.318542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.700097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-27T19:47:37.318834Z","caller":"traceutil/trace.go:171","msg":"trace[1368995003] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1625; }","duration":"156.037366ms","start":"2026-04-27T19:47:37.162775Z","end":"2026-04-27T19:47:37.318812Z","steps":["trace[1368995003] 'agreement among raft nodes before linearized reading' (duration: 155.543773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:37.648848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.36163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:47:37.649008Z","caller":"traceutil/trace.go:171","msg":"trace[1111591297] transaction","detail":"{read_only:false; response_revision:1626; number_of_response:1; }","duration":"326.040724ms","start":"2026-04-27T19:47:37.322919Z","end":"2026-04-27T19:47:37.64896Z","steps":["trace[1111591297] 'process raft request' (duration: 210.493789ms)","trace[1111591297] 'compare' (duration: 115.200965ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:47:37.649186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:37.322908Z","time spent":"326.188847ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:47:37.649292Z","caller":"traceutil/trace.go:171","msg":"trace[721804200] linearizableReadLoop","detail":"{readStateIndex:1697; appliedIndex:1696; }","duration":"304.021898ms","start":"2026-04-27T19:47:37.344906Z","end":"2026-04-27T19:47:37.648928Z","steps":["trace[721804200] 'read index received' (duration: 188.429152ms)","trace[721804200] 'applied index is now lower than readState.Index' (duration: 115.591786ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:47:37.64935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.444609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-27T19:47:37.649466Z","caller":"traceutil/trace.go:171","msg":"trace[1020564922] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1626; }","duration":"304.591003ms","start":"2026-04-27T19:47:37.344856Z","end":"2026-04-27T19:47:37.649447Z","steps":["trace[1020564922] 'agreement among raft nodes before linearized reading' (duration: 304.348027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:37.649555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:47:37.344839Z","time spent":"304.696395ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-27T19:47:37.905906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.678371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:47:37.906025Z","caller":"traceutil/trace.go:171","msg":"trace[811760939] linearizableReadLoop","detail":"{readStateIndex:1699; appliedIndex:1698; }","duration":"145.464939ms","start":"2026-04-27T19:47:37.760549Z","end":"2026-04-27T19:47:37.906014Z","steps":["trace[811760939] 'read index received' (duration: 61.792µs)","trace[811760939] 'applied index is now lower than readState.Index' (duration: 145.402237ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:47:37.906058Z","caller":"traceutil/trace.go:171","msg":"trace[370360347] transaction","detail":"{read_only:false; response_revision:1628; number_of_response:1; }","duration":"252.998281ms","start":"2026-04-27T19:47:37.653037Z","end":"2026-04-27T19:47:37.906035Z","steps":["trace[370360347] 'process raft request' (duration: 100.116575ms)","trace[370360347] 'compare' (duration: 152.530887ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:47:37.906171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.616972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:47:37.906209Z","caller":"traceutil/trace.go:171","msg":"trace[1096084740] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1628; }","duration":"145.730496ms","start":"2026-04-27T19:47:37.76047Z","end":"2026-04-27T19:47:37.9062Z","steps":["trace[1096084740] 'agreement among raft nodes before linearized reading' (duration: 145.651724ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:38.35941Z","caller":"traceutil/trace.go:171","msg":"trace[1267855388] transaction","detail":"{read_only:false; response_revision:1631; number_of_response:1; }","duration":"162.042817ms","start":"2026-04-27T19:47:38.197337Z","end":"2026-04-27T19:47:38.35938Z","steps":["trace[1267855388] 'process raft request' (duration: 157.171264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:38.652584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.460574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-27T19:47:38.652763Z","caller":"traceutil/trace.go:171","msg":"trace[79695536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1632; }","duration":"190.677359ms","start":"2026-04-27T19:47:38.462068Z","end":"2026-04-27T19:47:38.652745Z","steps":["trace[79695536] 'range keys from in-memory index tree' (duration: 190.30656ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:38.842364Z","caller":"traceutil/trace.go:171","msg":"trace[940452700] transaction","detail":"{read_only:false; response_revision:1633; number_of_response:1; }","duration":"185.365255ms","start":"2026-04-27T19:47:38.656971Z","end":"2026-04-27T19:47:38.842337Z","steps":["trace[940452700] 'process raft request' (duration: 185.166419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:39.111535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.61817ms","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-04-27T19:47:39.111609Z","caller":"traceutil/trace.go:171","msg":"trace[401295358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1633; }","duration":"196.753703ms","start":"2026-04-27T19:47:38.914838Z","end":"2026-04-27T19:47:39.111592Z","steps":["trace[401295358] 'range keys from in-memory index tree' (duration: 196.407544ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:54.684676Z","caller":"traceutil/trace.go:171","msg":"trace[683422813] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"107.281656ms","start":"2026-04-27T19:47:54.577349Z","end":"2026-04-27T19:47:54.68463Z","steps":["trace[683422813] 'process raft request' (duration: 103.210183ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:55.041209Z","caller":"traceutil/trace.go:171","msg":"trace[1286536109] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"106.65192ms","start":"2026-04-27T19:47:54.934417Z","end":"2026-04-27T19:47:55.041068Z","steps":["trace[1286536109] 'process raft request' (duration: 106.492836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:57.337402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.038918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-27T19:47:57.337457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.058285ms","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-04-27T19:47:57.337488Z","caller":"traceutil/trace.go:171","msg":"trace[721426774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1714; }","duration":"101.193712ms","start":"2026-04-27T19:47:57.236275Z","end":"2026-04-27T19:47:57.337468Z","steps":["trace[721426774] 'range keys from in-memory index tree' (duration: 100.779952ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:57.337524Z","caller":"traceutil/trace.go:171","msg":"trace[1665275159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1714; }","duration":"106.160647ms","start":"2026-04-27T19:47:57.231343Z","end":"2026-04-27T19:47:57.337504Z","steps":["trace[1665275159] 'range keys from in-memory index tree' (duration: 105.88503ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:57.474358Z","caller":"traceutil/trace.go:171","msg":"trace[1304223363] transaction","detail":"{read_only:false; response_revision:1716; number_of_response:1; }","duration":"131.487937ms","start":"2026-04-27T19:47:57.342845Z","end":"2026-04-27T19:47:57.474333Z","steps":["trace[1304223363] 'process raft request' (duration: 131.340523ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:58.007582Z","caller":"traceutil/trace.go:171","msg":"trace[1076319506] transaction","detail":"{read_only:false; response_revision:1718; number_of_response:1; }","duration":"106.410264ms","start":"2026-04-27T19:47:57.901149Z","end":"2026-04-27T19:47:58.00756Z","steps":["trace[1076319506] 'process raft request' (duration: 106.2502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:47:58.324914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.562602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:47:58.325043Z","caller":"traceutil/trace.go:171","msg":"trace[1797263527] linearizableReadLoop","detail":"{readStateIndex:1795; appliedIndex:1794; }","duration":"109.588753ms","start":"2026-04-27T19:47:58.215422Z","end":"2026-04-27T19:47:58.325011Z","steps":["trace[1797263527] 'read index received' (duration: 1.606071ms)","trace[1797263527] 'applied index is now lower than readState.Index' (duration: 107.980672ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:47:58.325185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.758438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-04-27T19:47:58.325218Z","caller":"traceutil/trace.go:171","msg":"trace[314455452] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1720; }","duration":"109.827889ms","start":"2026-04-27T19:47:58.215381Z","end":"2026-04-27T19:47:58.325209Z","steps":["trace[314455452] 'agreement among raft nodes before linearized reading' (duration: 109.689766ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:47:58.325425Z","caller":"traceutil/trace.go:171","msg":"trace[156327667] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"207.207486ms","start":"2026-04-27T19:47:58.118204Z","end":"2026-04-27T19:47:58.325411Z","steps":["trace[156327667] 'process raft request' (duration: 98.927326ms)","trace[156327667] 'compare' (duration: 107.45732ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:48:02.511377Z","caller":"traceutil/trace.go:171","msg":"trace[1013374587] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"163.419722ms","start":"2026-04-27T19:48:02.347924Z","end":"2026-04-27T19:48:02.511344Z","steps":["trace[1013374587] 'process raft request' (duration: 163.235527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:02.947776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.122592ms","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-04-27T19:48:02.947938Z","caller":"traceutil/trace.go:171","msg":"trace[1839015117] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1739; }","duration":"132.314307ms","start":"2026-04-27T19:48:02.815594Z","end":"2026-04-27T19:48:02.947908Z","steps":["trace[1839015117] 'range keys from in-memory index tree' (duration: 132.095402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:09.747552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.109432ms","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-04-27T19:48:09.747733Z","caller":"traceutil/trace.go:171","msg":"trace[1317835593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1772; }","duration":"199.341208ms","start":"2026-04-27T19:48:09.548373Z","end":"2026-04-27T19:48:09.747714Z","steps":["trace[1317835593] 'range keys from in-memory index tree' (duration: 198.905607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:09.747637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.886732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T19:48:09.74809Z","caller":"traceutil/trace.go:171","msg":"trace[118022784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1772; }","duration":"202.384634ms","start":"2026-04-27T19:48:09.545686Z","end":"2026-04-27T19:48:09.748071Z","steps":["trace[118022784] 'range keys from in-memory index tree' (duration: 201.715577ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:48:09.901921Z","caller":"traceutil/trace.go:171","msg":"trace[18465096] transaction","detail":"{read_only:false; response_revision:1774; number_of_response:1; }","duration":"149.279346ms","start":"2026-04-27T19:48:09.752578Z","end":"2026-04-27T19:48:09.901858Z","steps":["trace[18465096] 'process raft request' (duration: 103.4784ms)","trace[18465096] 'compare' (duration: 45.686093ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:48:10.207062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.89192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-27T19:48:10.207139Z","caller":"traceutil/trace.go:171","msg":"trace[1896274423] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1774; }","duration":"148.021344ms","start":"2026-04-27T19:48:10.0591Z","end":"2026-04-27T19:48:10.207121Z","steps":["trace[1896274423] 'range keys from in-memory index tree' (duration: 147.718376ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:48:13.367195Z","caller":"traceutil/trace.go:171","msg":"trace[1142324005] linearizableReadLoop","detail":"{readStateIndex:1865; appliedIndex:1864; }","duration":"113.495563ms","start":"2026-04-27T19:48:13.253671Z","end":"2026-04-27T19:48:13.367167Z","steps":["trace[1142324005] 'read index received' (duration: 67.133404ms)","trace[1142324005] 'applied index is now lower than readState.Index' (duration: 46.361199ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:48:13.367248Z","caller":"traceutil/trace.go:171","msg":"trace[374749217] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"170.646024ms","start":"2026-04-27T19:48:13.196558Z","end":"2026-04-27T19:48:13.367204Z","steps":["trace[374749217] 'process raft request' (duration: 124.258524ms)","trace[374749217] 'compare' (duration: 46.225796ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:48:13.367346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.646687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:48:13.367391Z","caller":"traceutil/trace.go:171","msg":"trace[623261825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1787; }","duration":"113.778291ms","start":"2026-04-27T19:48:13.253599Z","end":"2026-04-27T19:48:13.367377Z","steps":["trace[623261825] 'agreement among raft nodes before linearized reading' (duration: 113.663518ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:48:26.175593Z","caller":"traceutil/trace.go:171","msg":"trace[1020277934] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"136.498905ms","start":"2026-04-27T19:48:26.039064Z","end":"2026-04-27T19:48:26.175563Z","steps":["trace[1020277934] 'process raft request' (duration: 110.963105ms)","trace[1020277934] 'compare' (duration: 25.317105ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:48:34.862909Z","caller":"traceutil/trace.go:171","msg":"trace[79556218] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"104.115012ms","start":"2026-04-27T19:48:34.758763Z","end":"2026-04-27T19:48:34.862878Z","steps":["trace[79556218] 'process raft request' (duration: 103.956188ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:48:36.131438Z","caller":"traceutil/trace.go:171","msg":"trace[763756288] linearizableReadLoop","detail":"{readStateIndex:1974; appliedIndex:1973; }","duration":"134.262038ms","start":"2026-04-27T19:48:35.997153Z","end":"2026-04-27T19:48:36.131415Z","steps":["trace[763756288] 'read index received' (duration: 130.938975ms)","trace[763756288] 'applied index is now lower than readState.Index' (duration: 3.321983ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:48:36.131533Z","caller":"traceutil/trace.go:171","msg":"trace[267857665] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"357.882949ms","start":"2026-04-27T19:48:35.773625Z","end":"2026-04-27T19:48:36.131508Z","steps":["trace[267857665] 'process raft request' (duration: 354.488794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:36.131655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:48:35.773565Z","time spent":"358.028253ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:48:36.131658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.498135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-27T19:48:36.131719Z","caller":"traceutil/trace.go:171","msg":"trace[1380358579] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1891; }","duration":"134.605367ms","start":"2026-04-27T19:48:35.997096Z","end":"2026-04-27T19:48:36.131701Z","steps":["trace[1380358579] 'agreement among raft nodes before linearized reading' (duration: 134.434613ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:48:37.332415Z","caller":"traceutil/trace.go:171","msg":"trace[1975875110] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"226.439601ms","start":"2026-04-27T19:48:37.105946Z","end":"2026-04-27T19:48:37.332385Z","steps":["trace[1975875110] 'process raft request' (duration: 226.236455ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:48:48.680962Z","caller":"traceutil/trace.go:171","msg":"trace[420330573] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"117.128239ms","start":"2026-04-27T19:48:48.563814Z","end":"2026-04-27T19:48:48.680942Z","steps":["trace[420330573] 'process raft request' (duration: 116.574234ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:48:54.904803Z","caller":"traceutil/trace.go:171","msg":"trace[700995682] linearizableReadLoop","detail":"{readStateIndex:2063; appliedIndex:2062; }","duration":"154.339211ms","start":"2026-04-27T19:48:54.750447Z","end":"2026-04-27T19:48:54.904787Z","steps":["trace[700995682] 'read index received' (duration: 154.182628ms)","trace[700995682] 'applied index is now lower than readState.Index' (duration: 155.953µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:48:54.904947Z","caller":"traceutil/trace.go:171","msg":"trace[232175314] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"215.081086ms","start":"2026-04-27T19:48:54.689831Z","end":"2026-04-27T19:48:54.904912Z","steps":["trace[232175314] 'process raft request' (duration: 214.84782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:54.904992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.528637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T19:48:54.905194Z","caller":"traceutil/trace.go:171","msg":"trace[921791519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1977; }","duration":"154.763902ms","start":"2026-04-27T19:48:54.750411Z","end":"2026-04-27T19:48:54.905175Z","steps":["trace[921791519] 'agreement among raft nodes before linearized reading' (duration: 154.444464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:48:59.519067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.676275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:48:59.519148Z","caller":"traceutil/trace.go:171","msg":"trace[1398572255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1999; }","duration":"265.782187ms","start":"2026-04-27T19:48:59.253332Z","end":"2026-04-27T19:48:59.519114Z","steps":["trace[1398572255] 'range keys from in-memory index tree' (duration: 265.588142ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:17.20761Z","caller":"traceutil/trace.go:171","msg":"trace[2045098073] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"156.907437ms","start":"2026-04-27T19:49:17.050679Z","end":"2026-04-27T19:49:17.207586Z","steps":["trace[2045098073] 'process raft request' (duration: 156.753803ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:17.39158Z","caller":"traceutil/trace.go:171","msg":"trace[873128679] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"110.616674ms","start":"2026-04-27T19:49:17.280935Z","end":"2026-04-27T19:49:17.391552Z","steps":["trace[873128679] 'process raft request' (duration: 109.748622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:23.447582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.59959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:49:23.44783Z","caller":"traceutil/trace.go:171","msg":"trace[1609026860] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"125.320933ms","start":"2026-04-27T19:49:23.322471Z","end":"2026-04-27T19:49:23.447792Z","steps":["trace[1609026860] 'compare' (duration: 121.511148ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:23.575044Z","caller":"traceutil/trace.go:171","msg":"trace[1918459000] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"123.002676ms","start":"2026-04-27T19:49:23.452017Z","end":"2026-04-27T19:49:23.57502Z","steps":["trace[1918459000] 'process raft request' (duration: 108.995215ms)","trace[1918459000] 'compare' (duration: 13.835767ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:24.308015Z","caller":"traceutil/trace.go:171","msg":"trace[1754168062] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"259.529011ms","start":"2026-04-27T19:49:24.048456Z","end":"2026-04-27T19:49:24.307985Z","steps":["trace[1754168062] 'process raft request' (duration: 241.113589ms)","trace[1754168062] 'compare' (duration: 18.089694ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:41.682214Z","caller":"traceutil/trace.go:171","msg":"trace[159214906] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"149.416799ms","start":"2026-04-27T19:49:41.532772Z","end":"2026-04-27T19:49:41.682188Z","steps":["trace[159214906] 'process raft request' (duration: 149.273655ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:53.741701Z","caller":"traceutil/trace.go:171","msg":"trace[144216073] transaction","detail":"{read_only:false; response_revision:2263; number_of_response:1; }","duration":"162.376118ms","start":"2026-04-27T19:49:53.579226Z","end":"2026-04-27T19:49:53.741602Z","steps":["trace[144216073] 'process raft request' (duration: 162.022649ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:53.851146Z","caller":"traceutil/trace.go:171","msg":"trace[1847809400] transaction","detail":"{read_only:false; response_revision:2264; number_of_response:1; }","duration":"104.968198ms","start":"2026-04-27T19:49:53.746148Z","end":"2026-04-27T19:49:53.851116Z","steps":["trace[1847809400] 'process raft request' (duration: 100.110024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:54.08873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.952807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:49:54.088854Z","caller":"traceutil/trace.go:171","msg":"trace[1226164047] linearizableReadLoop","detail":"{readStateIndex:2365; appliedIndex:2364; }","duration":"137.726561ms","start":"2026-04-27T19:49:53.951107Z","end":"2026-04-27T19:49:54.088833Z","steps":["trace[1226164047] 'read index received' (duration: 1.485327ms)","trace[1226164047] 'applied index is now lower than readState.Index' (duration: 136.239674ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:49:54.089023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.910326ms","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-04-27T19:49:54.089052Z","caller":"traceutil/trace.go:171","msg":"trace[1484254277] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2266; }","duration":"137.968937ms","start":"2026-04-27T19:49:53.951074Z","end":"2026-04-27T19:49:54.089043Z","steps":["trace[1484254277] 'agreement among raft nodes before linearized reading' (duration: 137.816374ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:54.089084Z","caller":"traceutil/trace.go:171","msg":"trace[1976662989] transaction","detail":"{read_only:false; response_revision:2266; number_of_response:1; }","duration":"197.443769ms","start":"2026-04-27T19:49:53.891623Z","end":"2026-04-27T19:49:54.089066Z","steps":["trace[1976662989] 'process raft request' (duration: 61.052081ms)","trace[1976662989] 'compare' (duration: 135.801143ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:58.828838Z","caller":"traceutil/trace.go:171","msg":"trace[1902244141] transaction","detail":"{read_only:false; response_revision:2291; number_of_response:1; }","duration":"160.179293ms","start":"2026-04-27T19:49:58.668634Z","end":"2026-04-27T19:49:58.828813Z","steps":["trace[1902244141] 'process raft request' (duration: 160.005648ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:49:59.137534Z","caller":"traceutil/trace.go:171","msg":"trace[1397173234] linearizableReadLoop","detail":"{readStateIndex:2392; appliedIndex:2391; }","duration":"278.707945ms","start":"2026-04-27T19:49:58.858807Z","end":"2026-04-27T19:49:59.137515Z","steps":["trace[1397173234] 'read index received' (duration: 274.819717ms)","trace[1397173234] 'applied index is now lower than readState.Index' (duration: 3.887108ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:49:59.137591Z","caller":"traceutil/trace.go:171","msg":"trace[1948191138] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"305.321752ms","start":"2026-04-27T19:49:58.832242Z","end":"2026-04-27T19:49:59.137563Z","steps":["trace[1948191138] 'process raft request' (duration: 301.419054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:59.137713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.90051ms","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:526"} {"level":"info","ts":"2026-04-27T19:49:59.138051Z","caller":"traceutil/trace.go:171","msg":"trace[1079857771] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2292; }","duration":"279.244459ms","start":"2026-04-27T19:49:58.858793Z","end":"2026-04-27T19:49:59.138038Z","steps":["trace[1079857771] 'agreement among raft nodes before linearized reading' (duration: 278.819728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:49:59.137731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:49:58.832213Z","time spent":"305.443456ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:50:06.065605Z","caller":"traceutil/trace.go:171","msg":"trace[1492705261] linearizableReadLoop","detail":"{readStateIndex:2432; appliedIndex:2431; }","duration":"154.721613ms","start":"2026-04-27T19:50:05.910861Z","end":"2026-04-27T19:50:06.065583Z","steps":["trace[1492705261] 'read index received' (duration: 154.527278ms)","trace[1492705261] 'applied index is now lower than readState.Index' (duration: 193.245µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:50:06.065758Z","caller":"traceutil/trace.go:171","msg":"trace[1076126763] transaction","detail":"{read_only:false; response_revision:2330; number_of_response:1; }","duration":"265.705645ms","start":"2026-04-27T19:50:05.800013Z","end":"2026-04-27T19:50:06.065719Z","steps":["trace[1076126763] 'process raft request' (duration: 265.399017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:50:06.065793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.918209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T19:50:06.06596Z","caller":"traceutil/trace.go:171","msg":"trace[98315553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2330; }","duration":"155.138965ms","start":"2026-04-27T19:50:05.910812Z","end":"2026-04-27T19:50:06.065951Z","steps":["trace[98315553] 'agreement among raft nodes before linearized reading' (duration: 154.867938ms)"],"step_count":1}