{"level":"warn","ts":"2026-04-22T13:10:38.708339Z","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-22T13:10:38.708449Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-22T13:10:38.708535Z","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-22T13:10:38.708553Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-22T13:10:38.709516Z","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-22T13:10:38.710216Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-04-22T13:10:38.710294Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-22T13:10:39.048779Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"338.248105ms"} {"level":"info","ts":"2026-04-22T13:10:39.247434Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-04-22T13:10:39.247534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T13:10:39.24756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-04-22T13:10:39.247569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T13:10:39.247579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-04-22T13:10:39.247621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-04-22T13:10:39.26537Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T13:10:39.283565Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T13:10:39.302891Z","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-22T13:10:39.320051Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T13:10:39.320642Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T13:10:39.320424Z","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-22T13:10:39.321208Z","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-22T13:10:39.321231Z","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-22T13:10:39.325298Z","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-22T13:10:39.325648Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T13:10:39.32568Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T13:10:39.325711Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-22T13:10:39.325768Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-22T13:10:39.33131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-04-22T13:10:39.331504Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-22T13:10:40.248594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T13:10:40.248645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T13:10:40.248681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-04-22T13:10:40.248693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-04-22T13:10:40.248708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-22T13:10:40.248717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-04-22T13:10:40.248724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-22T13:10:40.25674Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T13:10:40.256749Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T13:10:40.256774Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T13:10:40.256923Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T13:10:40.257238Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T13:10:40.257315Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T13:10:40.259683Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T13:10:40.259817Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T13:10:40.260152Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T13:10:40.260467Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T13:10:40.261861Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"warn","ts":"2026-04-22T13:10:45.041466Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764890972918021,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:10:45.541686Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764890972918021,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:10:46.042793Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764890972918021,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:10:46.543365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764890972918021,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:10:47.043782Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764890972918021,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:10:47.544544Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764890972918021,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:10:48.045335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764890972918021,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T13:10:48.070863Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.641841166s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-22T13:10:49.540158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.99886329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-22T13:10:49.540267Z","caller":"traceutil/trace.go:171","msg":"trace[187766416] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999026995s","start":"2026-04-22T13:10:44.541217Z","end":"2026-04-22T13:10:49.540244Z","steps":["trace[187766416] 'agreement among raft nodes before linearized reading' (duration: 4.9989017s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:49.540327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:44.541193Z","time spent":"4.999123747s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/04/22 13:10:49 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-22T13:10:50.918921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.9996108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-22T13:10:50.919021Z","caller":"traceutil/trace.go:171","msg":"trace[1288718132] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999762765s","start":"2026-04-22T13:10:45.919236Z","end":"2026-04-22T13:10:50.918998Z","steps":["trace[1288718132] 'agreement among raft nodes before linearized reading' (duration: 4.999642541s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:50.919117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:45.919217Z","time spent":"4.999866477s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T13:10:51.430083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:44.428907Z","time spent":"7.001164074s","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-22T13:10:52.542424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:45.542003Z","time spent":"7.000408101s","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-22T13:10:53.873879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00006903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-22T13:10:53.873962Z","caller":"traceutil/trace.go:171","msg":"trace[762692125] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000223384s","start":"2026-04-22T13:10:51.873719Z","end":"2026-04-22T13:10:53.873943Z","steps":["trace[762692125] 'agreement among raft nodes before linearized reading' (duration: 2.000105121s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:53.874033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:51.873692Z","time spent":"2.000331176s","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T13:10:53.896322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:46.895633Z","time spent":"7.000682087s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-22T13:10:56.322627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.004301972s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-22T13:10:56.322732Z","caller":"traceutil/trace.go:171","msg":"trace[953019246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"10.004448245s","start":"2026-04-22T13:10:46.31826Z","end":"2026-04-22T13:10:56.322708Z","steps":["trace[953019246] 'agreement among raft nodes before linearized reading' (duration: 10.004326432s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:56.322782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:46.31824Z","time spent":"10.004531748s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} 2026/04/22 13:10:56 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-22T13:10:58.091337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998211122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-22T13:10:58.091429Z","caller":"traceutil/trace.go:171","msg":"trace[261610017] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.998360306s","start":"2026-04-22T13:10:53.093048Z","end":"2026-04-22T13:10:58.091408Z","steps":["trace[261610017] 'agreement among raft nodes before linearized reading' (duration: 4.998243273s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:58.091477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:53.093017Z","time spent":"4.998450849s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/04/22 13:10:58 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-22T13:10:58.817428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999454947s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-22T13:10:58.817514Z","caller":"traceutil/trace.go:171","msg":"trace[1504397885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999557251s","start":"2026-04-22T13:10:53.817936Z","end":"2026-04-22T13:10:58.817493Z","steps":["trace[1504397885] 'agreement among raft nodes before linearized reading' (duration: 4.999451728s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:10:58.817564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:53.817798Z","time spent":"4.999756016s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/04/22 13:10:58 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-22T13:10:58.936136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:51.935483Z","time spent":"7.000645739s","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-22T13:10:59.01181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:52.011509Z","time spent":"7.00029467s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-22T13:10:59.251691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:52.250866Z","time spent":"7.000813704s","remote":"127.0.0.1:34896","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-22T13:11:00.215921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.000941587s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-22T13:11:00.216075Z","caller":"traceutil/trace.go:171","msg":"trace[879296728] range","detail":"{range_begin:; range_end:; }","duration":"7.001118972s","start":"2026-04-22T13:10:53.214944Z","end":"2026-04-22T13:11:00.216063Z","steps":["trace[879296728] 'agreement among raft nodes before linearized reading' (duration: 7.000939817s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:00.216094Z","caller":"etcdhttp/health.go:262","msg":"serving /health false; Range fails","error":"context deadline exceeded"} {"level":"warn","ts":"2026-04-22T13:11:00.216144Z","caller":"etcdhttp/health.go:119","msg":"/health error","output":"{\"health\":\"false\",\"reason\":\"RANGE ERROR:context deadline exceeded\"}","status-code":503} {"level":"warn","ts":"2026-04-22T13:11:00.902036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:53.900971Z","time spent":"7.001059822s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-22T13:11:02.157223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999703435s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-22T13:11:02.157334Z","caller":"traceutil/trace.go:171","msg":"trace[613879554] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; }","duration":"9.99986745s","start":"2026-04-22T13:10:52.157442Z","end":"2026-04-22T13:11:02.157309Z","steps":["trace[613879554] 'agreement among raft nodes before linearized reading' (duration: 9.999733266s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:02.157432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:52.157422Z","time spent":"9.999976893s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-04-22T13:11:03.326386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:56.32542Z","time spent":"7.000923487s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-22T13:11:03.331315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"18.611429302s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-22T13:11:03.331431Z","caller":"traceutil/trace.go:171","msg":"trace[1684361563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"18.611615408s","start":"2026-04-22T13:10:44.719794Z","end":"2026-04-22T13:11:03.331409Z","steps":["trace[1684361563] 'agreement among raft nodes before linearized reading' (duration: 18.611468744s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:03.331508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:44.719754Z","time spent":"18.6117263s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/04/22 13:11:03 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-22T13:11:03.875274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000365389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-22T13:11:03.875418Z","caller":"traceutil/trace.go:171","msg":"trace[1752303205] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000563026s","start":"2026-04-22T13:11:01.874831Z","end":"2026-04-22T13:11:03.875394Z","steps":["trace[1752303205] 'agreement among raft nodes before linearized reading' (duration: 2.000400161s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:03.875475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:01.874812Z","time spent":"2.000650208s","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T13:11:05.79213Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"70df9db5506990e9","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-04-22T13:11:05.793451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999402568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-22T13:11:05.793591Z","caller":"traceutil/trace.go:171","msg":"trace[1494108846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999596454s","start":"2026-04-22T13:11:00.793956Z","end":"2026-04-22T13:11:05.793552Z","steps":["trace[1494108846] 'agreement among raft nodes before linearized reading' (duration: 4.999425989s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:05.793665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:00.793939Z","time spent":"4.999714777s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T13:11:06.017147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:59.016541Z","time spent":"7.00059574s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-22T13:11:06.110427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999202032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-22T13:11:06.110796Z","caller":"traceutil/trace.go:171","msg":"trace[201600246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999405498s","start":"2026-04-22T13:11:01.11108Z","end":"2026-04-22T13:11:06.110486Z","steps":["trace[201600246] 'agreement among raft nodes before linearized reading' (duration: 4.999256724s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:06.110872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:01.11106Z","time spent":"4.99979876s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/04/22 13:11:06 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-22T13:11:06.436516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:59.435597Z","time spent":"7.000911529s","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-22T13:11:07.90585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:00.905745Z","time spent":"7.000098277s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-22T13:11:09.255032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999669909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-22T13:11:09.255125Z","caller":"traceutil/trace.go:171","msg":"trace[886003784] range","detail":"{range_begin:/registry/minions/instance; range_end:; }","duration":"9.999824062s","start":"2026-04-22T13:10:59.255281Z","end":"2026-04-22T13:11:09.255105Z","steps":["trace[886003784] 'agreement among raft nodes before linearized reading' (duration: 9.999707839s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:09.255173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:59.255258Z","time spent":"9.999904385s","remote":"127.0.0.1:34896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":0,"request content":"key:\"/registry/minions/instance\" "} 2026/04/22 13:11:09 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-22T13:11:10.215181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.000375365s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-22T13:11:10.215384Z","caller":"traceutil/trace.go:171","msg":"trace[1012281667] range","detail":"{range_begin:; range_end:; }","duration":"7.000606091s","start":"2026-04-22T13:11:03.21476Z","end":"2026-04-22T13:11:10.215366Z","steps":["trace[1012281667] 'agreement among raft nodes before linearized reading' (duration: 7.000370165s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:10.215412Z","caller":"etcdhttp/health.go:262","msg":"serving /health false; Range fails","error":"context deadline exceeded"} {"level":"warn","ts":"2026-04-22T13:11:10.215466Z","caller":"etcdhttp/health.go:119","msg":"/health error","output":"{\"health\":\"false\",\"reason\":\"RANGE ERROR:context deadline exceeded\"}","status-code":503} {"level":"warn","ts":"2026-04-22T13:11:10.252229Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"22.137585917s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-22T13:11:10.736392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"22.664912358s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:11:10.736738Z","caller":"traceutil/trace.go:171","msg":"trace[817984485] linearizableReadLoop","detail":"{readStateIndex:224; appliedIndex:221; }","duration":"26.19542023s","start":"2026-04-22T13:10:44.541274Z","end":"2026-04-22T13:11:10.736694Z","steps":["trace[817984485] 'read index received' (duration: 3.529893825s)","trace[817984485] 'applied index is now lower than readState.Index' (duration: 22.665525355s)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:11:10.93908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.213009ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-22T13:11:10.939313Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"10.25µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"warn","ts":"2026-04-22T13:11:10.939538Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"70df9db5506990e9","error":"lease not found"} {"level":"info","ts":"2026-04-22T13:11:10.939651Z","caller":"traceutil/trace.go:171","msg":"trace[1161696810] linearizableReadLoop","detail":"{readStateIndex:237; appliedIndex:224; }","duration":"202.777526ms","start":"2026-04-22T13:11:10.736814Z","end":"2026-04-22T13:11:10.939591Z","steps":["trace[1161696810] 'read index received' (duration: 90.564619ms)","trace[1161696810] 'applied index is now lower than readState.Index' (duration: 112.212067ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:11:10.939739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"23.164896004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T13:11:10.939761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.682354364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3998"} {"level":"info","ts":"2026-04-22T13:11:10.939819Z","caller":"traceutil/trace.go:171","msg":"trace[764616633] transaction","detail":"{read_only:false; number_of_response:1; response_revision:222; }","duration":"4.91904028s","start":"2026-04-22T13:11:06.020773Z","end":"2026-04-22T13:11:10.939813Z","steps":["trace[764616633] 'process raft request' (duration: 4.918602718s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:10.939821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"17.647110117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T13:11:10.939898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:07.910838Z","time spent":"3.029058006s","remote":"127.0.0.1:34784","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-22T13:11:10.939757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.579845581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:11:10.939972Z","caller":"traceutil/trace.go:171","msg":"trace[478794522] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:222; }","duration":"8.580109258s","start":"2026-04-22T13:11:02.359852Z","end":"2026-04-22T13:11:10.939962Z","steps":["trace[478794522] 'agreement among raft nodes before linearized reading' (duration: 8.579877661s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:10.940056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:02.359812Z","time spent":"8.58023444s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-04-22T13:11:10.940063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:06.020744Z","time spent":"4.919102241s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":720,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:11:10.939793Z","caller":"traceutil/trace.go:171","msg":"trace[1429987848] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:222; }","duration":"23.164981896s","start":"2026-04-22T13:10:47.7748Z","end":"2026-04-22T13:11:10.939782Z","steps":["trace[1429987848] 'agreement among raft nodes before linearized reading' (duration: 23.164905963s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:10.940199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13102917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:11:10.940248Z","caller":"traceutil/trace.go:171","msg":"trace[2139228048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:222; }","duration":"1.1313693s","start":"2026-04-22T13:11:09.808855Z","end":"2026-04-22T13:11:10.940224Z","steps":["trace[2139228048] 'agreement among raft nodes before linearized reading' (duration: 1.130756443s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:10.940282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:09.808837Z","time spent":"1.131437432s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T13:11:10.94036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:47.774782Z","time spent":"23.165350037s","remote":"127.0.0.1:34852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"info","ts":"2026-04-22T13:11:10.939907Z","caller":"traceutil/trace.go:171","msg":"trace[1031695946] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:0; response_revision:222; }","duration":"17.647217099s","start":"2026-04-22T13:10:53.292662Z","end":"2026-04-22T13:11:10.939879Z","steps":["trace[1031695946] 'agreement among raft nodes before linearized reading' (duration: 17.647116477s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:10.940458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:10:53.292641Z","time spent":"17.647793497s","remote":"127.0.0.1:34732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-04-22T13:11:10.939708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.115908788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:11:10.940602Z","caller":"traceutil/trace.go:171","msg":"trace[916149586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:222; }","duration":"2.116855075s","start":"2026-04-22T13:11:08.823737Z","end":"2026-04-22T13:11:10.940593Z","steps":["trace[916149586] 'agreement among raft nodes before linearized reading' (duration: 2.115891648s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:10.940644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:08.82372Z","time spent":"2.116914296s","remote":"127.0.0.1:35030","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-22T13:11:10.939792Z","caller":"traceutil/trace.go:171","msg":"trace[985065286] transaction","detail":"{read_only:false; number_of_response:0; response_revision:222; }","duration":"4.004866683s","start":"2026-04-22T13:11:06.934919Z","end":"2026-04-22T13:11:10.939785Z","steps":["trace[985065286] 'process raft request' (duration: 4.004613566s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:10.940727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:06.93489Z","time spent":"4.005801519s","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:11:10.939841Z","caller":"traceutil/trace.go:171","msg":"trace[693633912] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:222; }","duration":"1.682484758s","start":"2026-04-22T13:11:09.257331Z","end":"2026-04-22T13:11:10.939816Z","steps":["trace[693633912] 'agreement among raft nodes before linearized reading' (duration: 1.682320904s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:10.940788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:09.257312Z","time spent":"1.683464165s","remote":"127.0.0.1:34896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4021,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-22T13:11:11.099817Z","caller":"traceutil/trace.go:171","msg":"trace[412937611] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"105.485321ms","start":"2026-04-22T13:11:10.994287Z","end":"2026-04-22T13:11:11.099773Z","steps":["trace[412937611] 'process raft request' (duration: 105.383239ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:11.099877Z","caller":"traceutil/trace.go:171","msg":"trace[1520601710] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"105.527071ms","start":"2026-04-22T13:11:10.994248Z","end":"2026-04-22T13:11:11.099775Z","steps":["trace[1520601710] 'process raft request' (duration: 72.550571ms)","trace[1520601710] 'compare' (duration: 32.734893ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:11:11.099964Z","caller":"traceutil/trace.go:171","msg":"trace[2021051691] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"104.030291ms","start":"2026-04-22T13:11:10.995913Z","end":"2026-04-22T13:11:11.099943Z","steps":["trace[2021051691] 'process raft request' (duration: 103.788854ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:11.10018Z","caller":"traceutil/trace.go:171","msg":"trace[89031169] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"105.45788ms","start":"2026-04-22T13:11:10.994266Z","end":"2026-04-22T13:11:11.099724Z","steps":["trace[89031169] 'process raft request' (duration: 105.379427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:11.100365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.934933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T13:11:11.100374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.125799ms","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-22T13:11:11.100406Z","caller":"traceutil/trace.go:171","msg":"trace[935559999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:234; }","duration":"105.990634ms","start":"2026-04-22T13:11:10.994405Z","end":"2026-04-22T13:11:11.100395Z","steps":["trace[935559999] 'agreement among raft nodes before linearized reading' (duration: 105.913413ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:11.100417Z","caller":"traceutil/trace.go:171","msg":"trace[1820513695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:234; }","duration":"106.21594ms","start":"2026-04-22T13:11:10.994188Z","end":"2026-04-22T13:11:11.100404Z","steps":["trace[1820513695] 'agreement among raft nodes before linearized reading' (duration: 106.113377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:11.100617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.953985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T13:11:11.100706Z","caller":"traceutil/trace.go:171","msg":"trace[1918632296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:234; }","duration":"105.006218ms","start":"2026-04-22T13:11:10.995639Z","end":"2026-04-22T13:11:11.100645Z","steps":["trace[1918632296] 'agreement among raft nodes before linearized reading' (duration: 104.649097ms)"],"step_count":1} 2026/04/22 13:11:11 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-22T13:11:11.607473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.379923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:11:11.607622Z","caller":"traceutil/trace.go:171","msg":"trace[1506453882] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"100.554145ms","start":"2026-04-22T13:11:11.50705Z","end":"2026-04-22T13:11:11.607604Z","steps":["trace[1506453882] 'process raft request' (duration: 100.527314ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:11.607774Z","caller":"traceutil/trace.go:171","msg":"trace[1880446838] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"125.483873ms","start":"2026-04-22T13:11:11.482284Z","end":"2026-04-22T13:11:11.607768Z","steps":["trace[1880446838] 'process raft request' (duration: 125.263217ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:11.607802Z","caller":"traceutil/trace.go:171","msg":"trace[959634297] linearizableReadLoop","detail":"{readStateIndex:270; appliedIndex:268; }","duration":"101.022858ms","start":"2026-04-22T13:11:11.506755Z","end":"2026-04-22T13:11:11.607778Z","steps":["trace[959634297] 'read index received' (duration: 69.415415ms)","trace[959634297] 'applied index is now lower than readState.Index' (duration: 31.604853ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:11:11.607914Z","caller":"traceutil/trace.go:171","msg":"trace[1120871888] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"125.610257ms","start":"2026-04-22T13:11:11.482275Z","end":"2026-04-22T13:11:11.607885Z","steps":["trace[1120871888] 'process raft request' (duration: 17.7725ms)","trace[1120871888] 'compare' (duration: 107.293911ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:11:11.608042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.0714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-22T13:11:11.608066Z","caller":"traceutil/trace.go:171","msg":"trace[1888010991] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:254; }","duration":"100.112882ms","start":"2026-04-22T13:11:11.507946Z","end":"2026-04-22T13:11:11.608059Z","steps":["trace[1888010991] 'agreement among raft nodes before linearized reading' (duration: 100.060891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:11.608036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.263815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-04-22T13:11:11.608155Z","caller":"traceutil/trace.go:171","msg":"trace[37274083] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:254; }","duration":"101.441919ms","start":"2026-04-22T13:11:11.506707Z","end":"2026-04-22T13:11:11.608149Z","steps":["trace[37274083] 'agreement among raft nodes before linearized reading' (duration: 101.188262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:11.84283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.126223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:11:11.84315Z","caller":"traceutil/trace.go:171","msg":"trace[1750015735] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"158.570576ms","start":"2026-04-22T13:11:11.684554Z","end":"2026-04-22T13:11:11.843124Z","steps":["trace[1750015735] 'process raft request' (duration: 54.047791ms)","trace[1750015735] 'compare' (duration: 103.889007ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:11:11.846523Z","caller":"traceutil/trace.go:171","msg":"trace[433299071] transaction","detail":"{read_only:false; number_of_response:0; response_revision:261; }","duration":"158.555245ms","start":"2026-04-22T13:11:11.687954Z","end":"2026-04-22T13:11:11.846509Z","steps":["trace[433299071] 'process raft request' (duration: 158.380971ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:11.84658Z","caller":"traceutil/trace.go:171","msg":"trace[2038062979] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"161.97804ms","start":"2026-04-22T13:11:11.684554Z","end":"2026-04-22T13:11:11.846532Z","steps":["trace[2038062979] 'process raft request' (duration: 161.409064ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:11.846693Z","caller":"traceutil/trace.go:171","msg":"trace[1200278965] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"159.80139ms","start":"2026-04-22T13:11:11.686864Z","end":"2026-04-22T13:11:11.846665Z","steps":["trace[1200278965] 'process raft request' (duration: 159.426419ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:11.846912Z","caller":"traceutil/trace.go:171","msg":"trace[84662580] transaction","detail":"{read_only:false; number_of_response:0; response_revision:259; }","duration":"161.752993ms","start":"2026-04-22T13:11:11.685148Z","end":"2026-04-22T13:11:11.846901Z","steps":["trace[84662580] 'process raft request' (duration: 161.035733ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:11.846984Z","caller":"traceutil/trace.go:171","msg":"trace[1505699421] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"159.154292ms","start":"2026-04-22T13:11:11.687815Z","end":"2026-04-22T13:11:11.846969Z","steps":["trace[1505699421] 'process raft request' (duration: 158.552345ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:11.847109Z","caller":"traceutil/trace.go:171","msg":"trace[1979175204] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"158.776882ms","start":"2026-04-22T13:11:11.688316Z","end":"2026-04-22T13:11:11.847093Z","steps":["trace[1979175204] 'process raft request' (duration: 158.085943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:11.847299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.761858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-04-22T13:11:11.847347Z","caller":"traceutil/trace.go:171","msg":"trace[573740097] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:263; }","duration":"159.843331ms","start":"2026-04-22T13:11:11.687489Z","end":"2026-04-22T13:11:11.847332Z","steps":["trace[573740097] 'agreement among raft nodes before linearized reading' (duration: 159.688476ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:11.846548Z","caller":"traceutil/trace.go:171","msg":"trace[1799677505] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"160.503168ms","start":"2026-04-22T13:11:11.685988Z","end":"2026-04-22T13:11:11.846491Z","steps":["trace[1799677505] 'process raft request' (duration: 160.250752ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:11.84646Z","caller":"traceutil/trace.go:171","msg":"trace[3901525] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:275; }","duration":"158.917755ms","start":"2026-04-22T13:11:11.687513Z","end":"2026-04-22T13:11:11.846431Z","steps":["trace[3901525] 'read index received' (duration: 51.125861ms)","trace[3901525] 'applied index is now lower than readState.Index' (duration: 107.790964ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:11:12.035715Z","caller":"traceutil/trace.go:171","msg":"trace[1847048232] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"182.478855ms","start":"2026-04-22T13:11:11.853215Z","end":"2026-04-22T13:11:12.035694Z","steps":["trace[1847048232] 'process raft request' (duration: 93.254702ms)","trace[1847048232] 'compare' (duration: 89.09708ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:11:12.0358Z","caller":"traceutil/trace.go:171","msg":"trace[498516586] linearizableReadLoop","detail":"{readStateIndex:284; appliedIndex:283; }","duration":"181.239451ms","start":"2026-04-22T13:11:11.854531Z","end":"2026-04-22T13:11:12.03577Z","steps":["trace[498516586] 'read index received' (duration: 91.951747ms)","trace[498516586] 'applied index is now lower than readState.Index' (duration: 89.286254ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:11:12.035834Z","caller":"traceutil/trace.go:171","msg":"trace[263995300] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"175.516214ms","start":"2026-04-22T13:11:11.860295Z","end":"2026-04-22T13:11:12.035811Z","steps":["trace[263995300] 'process raft request' (duration: 175.462783ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:12.035949Z","caller":"traceutil/trace.go:171","msg":"trace[1573317843] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"176.15571ms","start":"2026-04-22T13:11:11.859786Z","end":"2026-04-22T13:11:12.035941Z","steps":["trace[1573317843] 'process raft request' (duration: 175.919824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:12.035944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.393665ms","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-22T13:11:12.03598Z","caller":"traceutil/trace.go:171","msg":"trace[1737640586] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:266; }","duration":"181.441327ms","start":"2026-04-22T13:11:11.854528Z","end":"2026-04-22T13:11:12.035969Z","steps":["trace[1737640586] 'agreement among raft nodes before linearized reading' (duration: 181.321513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:43.096587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.911273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-04-22T13:11:43.096681Z","caller":"traceutil/trace.go:171","msg":"trace[1167737428] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:502; }","duration":"675.038018ms","start":"2026-04-22T13:11:42.421623Z","end":"2026-04-22T13:11:43.096661Z","steps":["trace[1167737428] 'range keys from in-memory index tree' (duration: 674.572673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:43.096719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:42.421605Z","time spent":"675.103899ms","remote":"127.0.0.1:34804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329975,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-04-22T13:11:44.655464Z","caller":"traceutil/trace.go:171","msg":"trace[1623307495] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"259.647084ms","start":"2026-04-22T13:11:44.395795Z","end":"2026-04-22T13:11:44.655442Z","steps":["trace[1623307495] 'process raft request' (duration: 259.541152ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:44.666745Z","caller":"traceutil/trace.go:171","msg":"trace[790929920] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"270.561665ms","start":"2026-04-22T13:11:44.396168Z","end":"2026-04-22T13:11:44.666729Z","steps":["trace[790929920] 'process raft request' (duration: 270.383091ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:44.666819Z","caller":"traceutil/trace.go:171","msg":"trace[615611740] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"270.583256ms","start":"2026-04-22T13:11:44.396222Z","end":"2026-04-22T13:11:44.666805Z","steps":["trace[615611740] 'process raft request' (duration: 270.437032ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:44.666939Z","caller":"traceutil/trace.go:171","msg":"trace[760906348] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"262.562005ms","start":"2026-04-22T13:11:44.404359Z","end":"2026-04-22T13:11:44.666921Z","steps":["trace[760906348] 'process raft request' (duration: 262.38356ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:44.667073Z","caller":"traceutil/trace.go:171","msg":"trace[572757704] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"249.792572ms","start":"2026-04-22T13:11:44.417241Z","end":"2026-04-22T13:11:44.667034Z","steps":["trace[572757704] 'process raft request' (duration: 249.574806ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:44.667227Z","caller":"traceutil/trace.go:171","msg":"trace[556623172] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"247.113299ms","start":"2026-04-22T13:11:44.420091Z","end":"2026-04-22T13:11:44.667204Z","steps":["trace[556623172] 'process raft request' (duration: 246.968905ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:44.667317Z","caller":"traceutil/trace.go:171","msg":"trace[1209353101] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"255.141431ms","start":"2026-04-22T13:11:44.411835Z","end":"2026-04-22T13:11:44.666977Z","steps":["trace[1209353101] 'process raft request' (duration: 254.946445ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:44.667389Z","caller":"traceutil/trace.go:171","msg":"trace[1220407879] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"245.986468ms","start":"2026-04-22T13:11:44.421384Z","end":"2026-04-22T13:11:44.667371Z","steps":["trace[1220407879] 'process raft request' (duration: 245.720061ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:44.667454Z","caller":"traceutil/trace.go:171","msg":"trace[84806222] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"244.691132ms","start":"2026-04-22T13:11:44.422719Z","end":"2026-04-22T13:11:44.66741Z","steps":["trace[84806222] 'process raft request' (duration: 244.558228ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:44.667501Z","caller":"traceutil/trace.go:171","msg":"trace[1634533923] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"228.389621ms","start":"2026-04-22T13:11:44.439102Z","end":"2026-04-22T13:11:44.667492Z","steps":["trace[1634533923] 'process raft request' (duration: 228.232108ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:44.667613Z","caller":"traceutil/trace.go:171","msg":"trace[816012880] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:539; }","duration":"143.763737ms","start":"2026-04-22T13:11:44.523829Z","end":"2026-04-22T13:11:44.667593Z","steps":["trace[816012880] 'read index received' (duration: 131.729156ms)","trace[816012880] 'applied index is now lower than readState.Index' (duration: 12.033421ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:11:44.667576Z","caller":"traceutil/trace.go:171","msg":"trace[346039807] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"202.134948ms","start":"2026-04-22T13:11:44.465429Z","end":"2026-04-22T13:11:44.667564Z","steps":["trace[346039807] 'process raft request' (duration: 201.998904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:44.667747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.893691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T13:11:44.667786Z","caller":"traceutil/trace.go:171","msg":"trace[1713695859] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:520; }","duration":"143.988673ms","start":"2026-04-22T13:11:44.523787Z","end":"2026-04-22T13:11:44.667776Z","steps":["trace[1713695859] 'agreement among raft nodes before linearized reading' (duration: 143.8799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:51.386139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.049616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:11:51.386237Z","caller":"traceutil/trace.go:171","msg":"trace[374908538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"302.193318ms","start":"2026-04-22T13:11:51.084021Z","end":"2026-04-22T13:11:51.386214Z","steps":["trace[374908538] 'range keys from in-memory index tree' (duration: 301.963242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:51.386291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:51.083973Z","time spent":"302.309192ms","remote":"127.0.0.1:34706","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-22T13:11:51.568443Z","caller":"traceutil/trace.go:171","msg":"trace[1219737137] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"459.424348ms","start":"2026-04-22T13:11:51.108981Z","end":"2026-04-22T13:11:51.568405Z","steps":["trace[1219737137] 'read index received' (duration: 458.005609ms)","trace[1219737137] 'applied index is now lower than readState.Index' (duration: 1.417349ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:11:51.568618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.617182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-22T13:11:51.568658Z","caller":"traceutil/trace.go:171","msg":"trace[1358068433] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:605; }","duration":"459.690685ms","start":"2026-04-22T13:11:51.108956Z","end":"2026-04-22T13:11:51.568647Z","steps":["trace[1358068433] 'agreement among raft nodes before linearized reading' (duration: 459.596732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:51.568694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:51.108944Z","time spent":"459.742306ms","remote":"127.0.0.1:34732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"info","ts":"2026-04-22T13:11:51.568485Z","caller":"traceutil/trace.go:171","msg":"trace[1247095339] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"461.046432ms","start":"2026-04-22T13:11:51.107401Z","end":"2026-04-22T13:11:51.568447Z","steps":["trace[1247095339] 'process raft request' (duration: 460.268341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:51.568849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.354113ms","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-22T13:11:51.569062Z","caller":"traceutil/trace.go:171","msg":"trace[197640319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"265.588909ms","start":"2026-04-22T13:11:51.303448Z","end":"2026-04-22T13:11:51.569037Z","steps":["trace[197640319] 'agreement among raft nodes before linearized reading' (duration: 265.26485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:51.56916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:51.107374Z","time spent":"461.568897ms","remote":"127.0.0.1:34804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:11:51.569571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.229709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T13:11:51.569646Z","caller":"traceutil/trace.go:171","msg":"trace[1499056870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"269.356292ms","start":"2026-04-22T13:11:51.300268Z","end":"2026-04-22T13:11:51.569625Z","steps":["trace[1499056870] 'agreement among raft nodes before linearized reading' (duration: 268.431067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:52.09045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.736164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:11:52.09085Z","caller":"traceutil/trace.go:171","msg":"trace[977633673] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"517.942473ms","start":"2026-04-22T13:11:51.572879Z","end":"2026-04-22T13:11:52.090821Z","steps":["trace[977633673] 'process raft request' (duration: 164.746936ms)","trace[977633673] 'compare' (duration: 352.560179ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:11:52.090881Z","caller":"traceutil/trace.go:171","msg":"trace[27980339] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"515.150545ms","start":"2026-04-22T13:11:51.575713Z","end":"2026-04-22T13:11:52.090864Z","steps":["trace[27980339] 'process raft request' (duration: 515.040162ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:52.091032Z","caller":"traceutil/trace.go:171","msg":"trace[770579916] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:642; }","duration":"443.8941ms","start":"2026-04-22T13:11:51.647125Z","end":"2026-04-22T13:11:52.091019Z","steps":["trace[770579916] 'read index received' (duration: 90.476607ms)","trace[770579916] 'applied index is now lower than readState.Index' (duration: 353.416873ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:11:52.091141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:51.575694Z","time spent":"515.30292ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:11:52.091144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:51.572849Z","time spent":"518.069136ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:11:52.091328Z","caller":"traceutil/trace.go:171","msg":"trace[1390302715] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"460.210019ms","start":"2026-04-22T13:11:51.631108Z","end":"2026-04-22T13:11:52.091318Z","steps":["trace[1390302715] 'process raft request' (duration: 459.85879ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:52.091285Z","caller":"traceutil/trace.go:171","msg":"trace[1830383084] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"510.910629ms","start":"2026-04-22T13:11:51.580314Z","end":"2026-04-22T13:11:52.091225Z","steps":["trace[1830383084] 'process raft request' (duration: 510.507608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:52.09135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.212787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gkcsm\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-22T13:11:52.091386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:51.631071Z","time spent":"460.288041ms","remote":"127.0.0.1:35096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:11:52.09143Z","caller":"traceutil/trace.go:171","msg":"trace[469737710] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gkcsm; range_end:; response_count:1; response_revision:609; }","duration":"444.319471ms","start":"2026-04-22T13:11:51.64709Z","end":"2026-04-22T13:11:52.091409Z","steps":["trace[469737710] 'agreement among raft nodes before linearized reading' (duration: 444.006812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:52.091479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:51.647075Z","time spent":"444.392134ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-gkcsm\" "} {"level":"warn","ts":"2026-04-22T13:11:52.091525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:51.580291Z","time spent":"511.094764ms","remote":"127.0.0.1:34732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:11:52.898993Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764890972918941,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T13:11:53.075408Z","caller":"traceutil/trace.go:171","msg":"trace[428506700] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"747.049944ms","start":"2026-04-22T13:11:52.328324Z","end":"2026-04-22T13:11:53.075374Z","steps":["trace[428506700] 'process raft request' (duration: 746.412507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:53.075569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:52.3283Z","time spent":"747.168008ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:11:53.43048Z","caller":"traceutil/trace.go:171","msg":"trace[689676164] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"1.04297132s","start":"2026-04-22T13:11:52.387461Z","end":"2026-04-22T13:11:53.430432Z","steps":["trace[689676164] 'process raft request' (duration: 1.042686252s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:53.430777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:52.387441Z","time spent":"1.043129395s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:11:53.431431Z","caller":"traceutil/trace.go:171","msg":"trace[129682377] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:647; }","duration":"1.033456489s","start":"2026-04-22T13:11:52.397938Z","end":"2026-04-22T13:11:53.431395Z","steps":["trace[129682377] 'read index received' (duration: 676.846708ms)","trace[129682377] 'applied index is now lower than readState.Index' (duration: 356.609101ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:11:53.431884Z","caller":"traceutil/trace.go:171","msg":"trace[1140525288] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"930.386613ms","start":"2026-04-22T13:11:52.501483Z","end":"2026-04-22T13:11:53.43187Z","steps":["trace[1140525288] 'process raft request' (duration: 928.968724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:53.43194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:52.501465Z","time spent":"930.447674ms","remote":"127.0.0.1:34804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:11:53.432045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.425168ms","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-22T13:11:53.432076Z","caller":"traceutil/trace.go:171","msg":"trace[1388305806] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:613; }","duration":"143.49659ms","start":"2026-04-22T13:11:53.288572Z","end":"2026-04-22T13:11:53.432068Z","steps":["trace[1388305806] 'agreement among raft nodes before linearized reading' (duration: 143.448308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:53.4321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.706669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-22T13:11:53.432054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033856519s","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-22T13:11:53.432325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026440944s","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-22T13:11:53.432456Z","caller":"traceutil/trace.go:171","msg":"trace[1700075714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"1.034477946s","start":"2026-04-22T13:11:52.397906Z","end":"2026-04-22T13:11:53.432384Z","steps":["trace[1700075714] 'agreement among raft nodes before linearized reading' (duration: 1.033747945s)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:53.432492Z","caller":"traceutil/trace.go:171","msg":"trace[198183715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"1.026994659s","start":"2026-04-22T13:11:52.405441Z","end":"2026-04-22T13:11:53.432436Z","steps":["trace[198183715] 'agreement among raft nodes before linearized reading' (duration: 1.02628118s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:53.432533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:52.39789Z","time spent":"1.03463312s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T13:11:53.432599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:52.40541Z","time spent":"1.027176505s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T13:11:53.432214Z","caller":"traceutil/trace.go:171","msg":"trace[1483189214] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:613; }","duration":"603.863833ms","start":"2026-04-22T13:11:52.828323Z","end":"2026-04-22T13:11:53.432187Z","steps":["trace[1483189214] 'agreement among raft nodes before linearized reading' (duration: 603.711558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:11:53.432852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:11:52.828301Z","time spent":"604.534721ms","remote":"127.0.0.1:35148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-04-22T13:11:55.169862Z","caller":"traceutil/trace.go:171","msg":"trace[1129358382] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"254.564114ms","start":"2026-04-22T13:11:54.915269Z","end":"2026-04-22T13:11:55.169833Z","steps":["trace[1129358382] 'read index received' (duration: 253.397152ms)","trace[1129358382] 'applied index is now lower than readState.Index' (duration: 1.165642ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:11:55.170338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.031958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:11:55.170436Z","caller":"traceutil/trace.go:171","msg":"trace[199334259] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:652; }","duration":"255.174031ms","start":"2026-04-22T13:11:54.915241Z","end":"2026-04-22T13:11:55.170415Z","steps":["trace[199334259] 'agreement among raft nodes before linearized reading' (duration: 254.849612ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:11:55.170242Z","caller":"traceutil/trace.go:171","msg":"trace[924758860] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"255.370717ms","start":"2026-04-22T13:11:54.91481Z","end":"2026-04-22T13:11:55.170181Z","steps":["trace[924758860] 'process raft request' (duration: 254.260606ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:00.591953Z","caller":"traceutil/trace.go:171","msg":"trace[934494336] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"105.233974ms","start":"2026-04-22T13:12:00.486699Z","end":"2026-04-22T13:12:00.591933Z","steps":["trace[934494336] 'process raft request' (duration: 105.130081ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:01.249275Z","caller":"traceutil/trace.go:171","msg":"trace[635045576] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"106.235531ms","start":"2026-04-22T13:12:01.143009Z","end":"2026-04-22T13:12:01.249244Z","steps":["trace[635045576] 'process raft request' (duration: 23.852808ms)","trace[635045576] 'compare' (duration: 82.168217ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:12:06.286693Z","caller":"traceutil/trace.go:171","msg":"trace[73269617] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"129.709159ms","start":"2026-04-22T13:12:06.15695Z","end":"2026-04-22T13:12:06.286659Z","steps":["trace[73269617] 'process raft request' (duration: 129.456452ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:06.288033Z","caller":"traceutil/trace.go:171","msg":"trace[745558421] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"130.736818ms","start":"2026-04-22T13:12:06.15727Z","end":"2026-04-22T13:12:06.288007Z","steps":["trace[745558421] 'process raft request' (duration: 130.579423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:07.105167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.315287ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-22T13:12:07.729574Z","caller":"traceutil/trace.go:171","msg":"trace[23376358] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"221.665697ms","start":"2026-04-22T13:12:07.507875Z","end":"2026-04-22T13:12:07.72954Z","steps":["trace[23376358] 'process raft request' (duration: 189.482649ms)","trace[23376358] 'compare' (duration: 32.064845ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:12:09.677701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.438395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-22T13:12:09.677714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.184661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:09.677785Z","caller":"traceutil/trace.go:171","msg":"trace[1200891050] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:784; }","duration":"165.592099ms","start":"2026-04-22T13:12:09.512171Z","end":"2026-04-22T13:12:09.677763Z","steps":["trace[1200891050] 'count revisions from in-memory index tree' (duration: 165.343432ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:09.677785Z","caller":"traceutil/trace.go:171","msg":"trace[908368386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"592.295404ms","start":"2026-04-22T13:12:09.085475Z","end":"2026-04-22T13:12:09.677771Z","steps":["trace[908368386] 'range keys from in-memory index tree' (duration: 592.100409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:09.677829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:09.085455Z","time spent":"592.362376ms","remote":"127.0.0.1:34700","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-22T13:12:09.677956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.414942ms","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-22T13:12:09.678023Z","caller":"traceutil/trace.go:171","msg":"trace[249864822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:784; }","duration":"120.533226ms","start":"2026-04-22T13:12:09.557474Z","end":"2026-04-22T13:12:09.678007Z","steps":["trace[249864822] 'range keys from in-memory index tree' (duration: 120.253539ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:09.798771Z","caller":"traceutil/trace.go:171","msg":"trace[1154983804] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"116.650308ms","start":"2026-04-22T13:12:09.682091Z","end":"2026-04-22T13:12:09.798742Z","steps":["trace[1154983804] 'process raft request' (duration: 116.494755ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:13.847318Z","caller":"traceutil/trace.go:171","msg":"trace[357982657] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:844; }","duration":"149.699213ms","start":"2026-04-22T13:12:13.697597Z","end":"2026-04-22T13:12:13.847297Z","steps":["trace[357982657] 'read index received' (duration: 149.55817ms)","trace[357982657] 'applied index is now lower than readState.Index' (duration: 140.143µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:12:13.84743Z","caller":"traceutil/trace.go:171","msg":"trace[237293606] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"164.348398ms","start":"2026-04-22T13:12:13.683045Z","end":"2026-04-22T13:12:13.847393Z","steps":["trace[237293606] 'process raft request' (duration: 164.100881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:13.847558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.935259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T13:12:13.84762Z","caller":"traceutil/trace.go:171","msg":"trace[130207195] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:802; }","duration":"150.042763ms","start":"2026-04-22T13:12:13.697562Z","end":"2026-04-22T13:12:13.847604Z","steps":["trace[130207195] 'agreement among raft nodes before linearized reading' (duration: 149.92196ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:28.10426Z","caller":"traceutil/trace.go:171","msg":"trace[546109941] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"107.990732ms","start":"2026-04-22T13:12:27.996233Z","end":"2026-04-22T13:12:28.104223Z","steps":["trace[546109941] 'process raft request' (duration: 107.727345ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:43.123741Z","caller":"traceutil/trace.go:171","msg":"trace[1565123207] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"100.492815ms","start":"2026-04-22T13:12:43.023226Z","end":"2026-04-22T13:12:43.123719Z","steps":["trace[1565123207] 'process raft request' (duration: 96.835114ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:43.259879Z","caller":"traceutil/trace.go:171","msg":"trace[2120101400] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"122.03734ms","start":"2026-04-22T13:12:43.13782Z","end":"2026-04-22T13:12:43.259858Z","steps":["trace[2120101400] 'process raft request' (duration: 121.235918ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:44.870343Z","caller":"traceutil/trace.go:171","msg":"trace[1226647676] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"255.235537ms","start":"2026-04-22T13:12:44.615081Z","end":"2026-04-22T13:12:44.870316Z","steps":["trace[1226647676] 'process raft request' (duration: 255.042342ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:44.91806Z","caller":"traceutil/trace.go:171","msg":"trace[987338521] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"158.445894ms","start":"2026-04-22T13:12:44.759584Z","end":"2026-04-22T13:12:44.91803Z","steps":["trace[987338521] 'process raft request' (duration: 130.834192ms)","trace[987338521] 'compare' (duration: 27.490079ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:12:46.004747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.627399ms","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-22T13:12:46.004859Z","caller":"traceutil/trace.go:171","msg":"trace[1135965928] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1002; }","duration":"978.783023ms","start":"2026-04-22T13:12:45.026054Z","end":"2026-04-22T13:12:46.004837Z","steps":["trace[1135965928] 'range keys from in-memory index tree' (duration: 978.456355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:46.004911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:45.026034Z","time spent":"978.861726ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T13:12:46.005114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.229395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:46.005147Z","caller":"traceutil/trace.go:171","msg":"trace[694937295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"921.295477ms","start":"2026-04-22T13:12:45.083841Z","end":"2026-04-22T13:12:46.005137Z","steps":["trace[694937295] 'range keys from in-memory index tree' (duration: 921.054589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:46.005165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:45.08382Z","time spent":"921.341027ms","remote":"127.0.0.1:34706","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-22T13:12:46.00538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.48354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:46.005401Z","caller":"traceutil/trace.go:171","msg":"trace[419580512] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1002; }","duration":"704.536221ms","start":"2026-04-22T13:12:45.300859Z","end":"2026-04-22T13:12:46.005395Z","steps":["trace[419580512] 'count revisions from in-memory index tree' (duration: 704.431129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:46.005421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:45.300819Z","time spent":"704.595113ms","remote":"127.0.0.1:34858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-22T13:12:46.005969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.715124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:12:46.006063Z","caller":"traceutil/trace.go:171","msg":"trace[950351728] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"549.602213ms","start":"2026-04-22T13:12:45.456442Z","end":"2026-04-22T13:12:46.006044Z","steps":["trace[950351728] 'process raft request' (duration: 186.734765ms)","trace[950351728] 'compare' (duration: 362.437846ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:12:46.006589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:45.456416Z","time spent":"549.8224ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:12:46.516423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764890972919766,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T13:12:46.52717Z","caller":"traceutil/trace.go:171","msg":"trace[1039970094] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"516.035088ms","start":"2026-04-22T13:12:46.011117Z","end":"2026-04-22T13:12:46.527152Z","steps":["trace[1039970094] 'process raft request' (duration: 515.918824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:46.527297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:46.011097Z","time spent":"516.12195ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:12:46.534363Z","caller":"traceutil/trace.go:171","msg":"trace[1655997407] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1052; }","duration":"518.694761ms","start":"2026-04-22T13:12:46.015647Z","end":"2026-04-22T13:12:46.534342Z","steps":["trace[1655997407] 'read index received' (duration: 511.41561ms)","trace[1655997407] 'applied index is now lower than readState.Index' (duration: 7.277561ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:12:46.534509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.845605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:46.534574Z","caller":"traceutil/trace.go:171","msg":"trace[941286622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1004; }","duration":"518.950838ms","start":"2026-04-22T13:12:46.01561Z","end":"2026-04-22T13:12:46.53456Z","steps":["trace[941286622] 'agreement among raft nodes before linearized reading' (duration: 518.840765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:46.534608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:46.01559Z","time spent":"519.011719ms","remote":"127.0.0.1:34700","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-22T13:12:46.534621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.988908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-22T13:12:46.534672Z","caller":"traceutil/trace.go:171","msg":"trace[681942761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1004; }","duration":"260.089371ms","start":"2026-04-22T13:12:46.274571Z","end":"2026-04-22T13:12:46.53466Z","steps":["trace[681942761] 'agreement among raft nodes before linearized reading' (duration: 259.902555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:46.534684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.516726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-22T13:12:46.534735Z","caller":"traceutil/trace.go:171","msg":"trace[1717353763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1004; }","duration":"493.594407ms","start":"2026-04-22T13:12:46.041126Z","end":"2026-04-22T13:12:46.53472Z","steps":["trace[1717353763] 'agreement among raft nodes before linearized reading' (duration: 493.34392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:46.534781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:46.041114Z","time spent":"493.657369ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T13:13:05.690023Z","caller":"traceutil/trace.go:171","msg":"trace[1215148608] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"255.192545ms","start":"2026-04-22T13:13:05.434795Z","end":"2026-04-22T13:13:05.689988Z","steps":["trace[1215148608] 'process raft request' (duration: 254.854566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:06.219787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.395754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:06.219895Z","caller":"traceutil/trace.go:171","msg":"trace[1719719001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1068; }","duration":"133.511245ms","start":"2026-04-22T13:13:06.086335Z","end":"2026-04-22T13:13:06.219847Z","steps":["trace[1719719001] 'range keys from in-memory index tree' (duration: 133.30028ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:06.220089Z","caller":"traceutil/trace.go:171","msg":"trace[672469441] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"133.007232ms","start":"2026-04-22T13:13:06.087048Z","end":"2026-04-22T13:13:06.220055Z","steps":["trace[672469441] 'process raft request' (duration: 122.255095ms)","trace[672469441] 'compare' (duration: 10.278104ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:06.854191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.933882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T13:13:06.854254Z","caller":"traceutil/trace.go:171","msg":"trace[1117500317] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1069; }","duration":"533.038086ms","start":"2026-04-22T13:13:06.321202Z","end":"2026-04-22T13:13:06.85424Z","steps":["trace[1117500317] 'count revisions from in-memory index tree' (duration: 532.854811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:06.854297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:06.32118Z","time spent":"533.095697ms","remote":"127.0.0.1:35216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-04-22T13:13:06.881359Z","caller":"traceutil/trace.go:171","msg":"trace[503185655] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"266.723054ms","start":"2026-04-22T13:13:06.614612Z","end":"2026-04-22T13:13:06.881336Z","steps":["trace[503185655] 'read index received' (duration: 212.484936ms)","trace[503185655] 'applied index is now lower than readState.Index' (duration: 54.234108ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:06.881684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.051643ms","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-22T13:13:06.881734Z","caller":"traceutil/trace.go:171","msg":"trace[1607256883] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1069; }","duration":"267.156465ms","start":"2026-04-22T13:13:06.614564Z","end":"2026-04-22T13:13:06.881721Z","steps":["trace[1607256883] 'agreement among raft nodes before linearized reading' (duration: 266.934299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:06.881836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.147529ms","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-22T13:13:06.88199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.594531ms","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-22T13:13:06.88187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.709466ms","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-22T13:13:06.88211Z","caller":"traceutil/trace.go:171","msg":"trace[1586792746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1069; }","duration":"253.486698ms","start":"2026-04-22T13:13:06.628526Z","end":"2026-04-22T13:13:06.882013Z","steps":["trace[1586792746] 'agreement among raft nodes before linearized reading' (duration: 253.068757ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:06.882233Z","caller":"traceutil/trace.go:171","msg":"trace[694065989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1069; }","duration":"245.693693ms","start":"2026-04-22T13:13:06.636332Z","end":"2026-04-22T13:13:06.882026Z","steps":["trace[694065989] 'agreement among raft nodes before linearized reading' (duration: 245.6002ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:06.882395Z","caller":"traceutil/trace.go:171","msg":"trace[787842365] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1069; }","duration":"156.955393ms","start":"2026-04-22T13:13:06.725076Z","end":"2026-04-22T13:13:06.882032Z","steps":["trace[787842365] 'agreement among raft nodes before linearized reading' (duration: 156.593603ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:07.018804Z","caller":"traceutil/trace.go:171","msg":"trace[838011064] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"129.945948ms","start":"2026-04-22T13:13:06.888825Z","end":"2026-04-22T13:13:07.018771Z","steps":["trace[838011064] 'process raft request' (duration: 129.689131ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:07.019039Z","caller":"traceutil/trace.go:171","msg":"trace[1993346341] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"129.871316ms","start":"2026-04-22T13:13:06.889139Z","end":"2026-04-22T13:13:07.01901Z","steps":["trace[1993346341] 'process raft request' (duration: 129.592118ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:07.019226Z","caller":"traceutil/trace.go:171","msg":"trace[1414147489] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"129.742022ms","start":"2026-04-22T13:13:06.889447Z","end":"2026-04-22T13:13:07.019189Z","steps":["trace[1414147489] 'process raft request' (duration: 129.485334ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:07.135433Z","caller":"traceutil/trace.go:171","msg":"trace[694204348] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"111.742386ms","start":"2026-04-22T13:13:07.023659Z","end":"2026-04-22T13:13:07.135402Z","steps":["trace[694204348] 'process raft request' (duration: 75.160945ms)","trace[694204348] 'compare' (duration: 36.451887ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:10.598195Z","caller":"traceutil/trace.go:171","msg":"trace[1678311216] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"310.547363ms","start":"2026-04-22T13:13:10.287615Z","end":"2026-04-22T13:13:10.598163Z","steps":["trace[1678311216] 'process raft request' (duration: 310.371659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:10.598374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:10.287598Z","time spent":"310.686647ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:20.255927Z","caller":"traceutil/trace.go:171","msg":"trace[181960299] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"127.201266ms","start":"2026-04-22T13:13:20.128701Z","end":"2026-04-22T13:13:20.255902Z","steps":["trace[181960299] 'process raft request' (duration: 121.497146ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:20.256078Z","caller":"traceutil/trace.go:171","msg":"trace[318221367] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"124.402017ms","start":"2026-04-22T13:13:20.131643Z","end":"2026-04-22T13:13:20.256045Z","steps":["trace[318221367] 'process raft request' (duration: 123.867692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:20.596877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:20.261553Z","time spent":"335.317367ms","remote":"127.0.0.1:34784","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-22T13:13:20.597131Z","caller":"traceutil/trace.go:171","msg":"trace[1321234044] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"322.560008ms","start":"2026-04-22T13:13:20.274493Z","end":"2026-04-22T13:13:20.597053Z","steps":["trace[1321234044] 'process raft request' (duration: 322.479656ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:20.597227Z","caller":"traceutil/trace.go:171","msg":"trace[1413650434] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"333.895007ms","start":"2026-04-22T13:13:20.263292Z","end":"2026-04-22T13:13:20.597187Z","steps":["trace[1413650434] 'process raft request' (duration: 333.467585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:20.597409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:20.274481Z","time spent":"322.770704ms","remote":"127.0.0.1:34804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":32849,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:20.597809Z","caller":"traceutil/trace.go:171","msg":"trace[1750533074] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"332.528488ms","start":"2026-04-22T13:13:20.264572Z","end":"2026-04-22T13:13:20.597101Z","steps":["trace[1750533074] 'process raft request' (duration: 332.313692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:20.597912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:20.264564Z","time spent":"333.29751ms","remote":"127.0.0.1:35208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10857,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:13:20.599141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:20.263279Z","time spent":"334.024ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10713,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:13:20.76464Z","caller":"traceutil/trace.go:171","msg":"trace[388730215] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"426.938094ms","start":"2026-04-22T13:13:20.337652Z","end":"2026-04-22T13:13:20.76459Z","steps":["trace[388730215] 'process raft request' (duration: 336.023857ms)","trace[388730215] 'compare' (duration: 90.709521ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:20.764813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:20.337627Z","time spent":"427.094558ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9680,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:20.764861Z","caller":"traceutil/trace.go:171","msg":"trace[683976662] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"423.283011ms","start":"2026-04-22T13:13:20.34153Z","end":"2026-04-22T13:13:20.764813Z","steps":["trace[683976662] 'process raft request' (duration: 422.994503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:20.765101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:20.341507Z","time spent":"423.452535ms","remote":"127.0.0.1:35216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9358,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:20.851455Z","caller":"traceutil/trace.go:171","msg":"trace[877544997] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"252.035976ms","start":"2026-04-22T13:13:20.599384Z","end":"2026-04-22T13:13:20.85142Z","steps":["trace[877544997] 'process raft request' (duration: 251.869991ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:20.8516Z","caller":"traceutil/trace.go:171","msg":"trace[1030651714] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"248.148476ms","start":"2026-04-22T13:13:20.603432Z","end":"2026-04-22T13:13:20.851581Z","steps":["trace[1030651714] 'process raft request' (duration: 247.94617ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:20.851632Z","caller":"traceutil/trace.go:171","msg":"trace[1187021044] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"251.982473ms","start":"2026-04-22T13:13:20.599617Z","end":"2026-04-22T13:13:20.851599Z","steps":["trace[1187021044] 'process raft request' (duration: 251.735917ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:20.851751Z","caller":"traceutil/trace.go:171","msg":"trace[1144762780] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"246.983504ms","start":"2026-04-22T13:13:20.60476Z","end":"2026-04-22T13:13:20.851744Z","steps":["trace[1144762780] 'process raft request' (duration: 246.701696ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:20.851858Z","caller":"traceutil/trace.go:171","msg":"trace[2014347532] linearizableReadLoop","detail":"{readStateIndex:1208; appliedIndex:1202; }","duration":"243.731702ms","start":"2026-04-22T13:13:20.608115Z","end":"2026-04-22T13:13:20.851846Z","steps":["trace[2014347532] 'read index received' (duration: 65.525672ms)","trace[2014347532] 'applied index is now lower than readState.Index' (duration: 178.20483ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:20.851912Z","caller":"traceutil/trace.go:171","msg":"trace[109135469] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"243.603919ms","start":"2026-04-22T13:13:20.608299Z","end":"2026-04-22T13:13:20.851903Z","steps":["trace[109135469] 'process raft request' (duration: 243.184947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:20.852188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.064872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:10872"} {"level":"info","ts":"2026-04-22T13:13:20.852243Z","caller":"traceutil/trace.go:171","msg":"trace[1252300171] range","detail":"{range_begin:/registry/deployments/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:1152; }","duration":"244.165464ms","start":"2026-04-22T13:13:20.608063Z","end":"2026-04-22T13:13:20.852228Z","steps":["trace[1252300171] 'agreement among raft nodes before linearized reading' (duration: 243.99611ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:20.852092Z","caller":"traceutil/trace.go:171","msg":"trace[1636282375] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"222.095845ms","start":"2026-04-22T13:13:20.629955Z","end":"2026-04-22T13:13:20.852051Z","steps":["trace[1636282375] 'process raft request' (duration: 221.57784ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:20.953124Z","caller":"traceutil/trace.go:171","msg":"trace[2036951985] linearizableReadLoop","detail":"{readStateIndex:1211; appliedIndex:1210; }","duration":"101.210905ms","start":"2026-04-22T13:13:20.851888Z","end":"2026-04-22T13:13:20.953099Z","steps":["trace[2036951985] 'read index received' (duration: 56.263112ms)","trace[2036951985] 'applied index is now lower than readState.Index' (duration: 44.946663ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:20.953187Z","caller":"traceutil/trace.go:171","msg":"trace[1966472267] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"183.740985ms","start":"2026-04-22T13:13:20.769406Z","end":"2026-04-22T13:13:20.953147Z","steps":["trace[1966472267] 'process raft request' (duration: 138.73118ms)","trace[1966472267] 'compare' (duration: 44.833881ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:20.953354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.55593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T13:13:20.953427Z","caller":"traceutil/trace.go:171","msg":"trace[1874390654] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1153; }","duration":"284.675154ms","start":"2026-04-22T13:13:20.668735Z","end":"2026-04-22T13:13:20.95341Z","steps":["trace[1874390654] 'agreement among raft nodes before linearized reading' (duration: 284.420867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:20.953467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.833528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363682"} {"level":"info","ts":"2026-04-22T13:13:20.953508Z","caller":"traceutil/trace.go:171","msg":"trace[1105638546] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1153; }","duration":"300.928541ms","start":"2026-04-22T13:13:20.652567Z","end":"2026-04-22T13:13:20.953495Z","steps":["trace[1105638546] 'agreement among raft nodes before linearized reading' (duration: 300.648583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:20.953554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:20.652548Z","time spent":"300.996633ms","remote":"127.0.0.1:34804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":6,"response size":363705,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-04-22T13:13:20.953864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.701398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-tw9hq\" ","response":"range_response_count:1 size:9695"} {"level":"info","ts":"2026-04-22T13:13:20.953983Z","caller":"traceutil/trace.go:171","msg":"trace[1991000915] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-tw9hq; range_end:; response_count:1; response_revision:1153; }","duration":"181.863864ms","start":"2026-04-22T13:13:20.772095Z","end":"2026-04-22T13:13:20.953959Z","steps":["trace[1991000915] 'agreement among raft nodes before linearized reading' (duration: 181.537504ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:20.995771Z","caller":"traceutil/trace.go:171","msg":"trace[1098250774] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"132.735482ms","start":"2026-04-22T13:13:20.863016Z","end":"2026-04-22T13:13:20.995752Z","steps":["trace[1098250774] 'process raft request' (duration: 132.63755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:20.996148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.268813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/ceph-csi-rbd-provisioner-69cb97fb6\" ","response":"range_response_count:1 size:10843"} {"level":"info","ts":"2026-04-22T13:13:20.996397Z","caller":"traceutil/trace.go:171","msg":"trace[1723531937] range","detail":"{range_begin:/registry/replicasets/kube-system/ceph-csi-rbd-provisioner-69cb97fb6; range_end:; response_count:1; response_revision:1154; }","duration":"135.565211ms","start":"2026-04-22T13:13:20.860804Z","end":"2026-04-22T13:13:20.996369Z","steps":["trace[1723531937] 'agreement among raft nodes before linearized reading' (duration: 135.097847ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:23.011678Z","caller":"traceutil/trace.go:171","msg":"trace[384031521] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"152.362664ms","start":"2026-04-22T13:13:22.85929Z","end":"2026-04-22T13:13:23.011653Z","steps":["trace[384031521] 'process raft request' (duration: 152.206849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:24.362787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.753771ms","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-22T13:13:24.362887Z","caller":"traceutil/trace.go:171","msg":"trace[1991965604] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1172; }","duration":"361.872294ms","start":"2026-04-22T13:13:24.001001Z","end":"2026-04-22T13:13:24.362873Z","steps":["trace[1991965604] 'range keys from in-memory index tree' (duration: 361.73589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:24.363411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.431948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:13:24.363507Z","caller":"traceutil/trace.go:171","msg":"trace[452105992] linearizableReadLoop","detail":"{readStateIndex:1232; appliedIndex:1231; }","duration":"1.184727607s","start":"2026-04-22T13:13:23.178763Z","end":"2026-04-22T13:13:24.363491Z","steps":["trace[452105992] 'read index received' (duration: 498.139505ms)","trace[452105992] 'applied index is now lower than readState.Index' (duration: 686.587112ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:24.363629Z","caller":"traceutil/trace.go:171","msg":"trace[2129060049] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"1.265643083s","start":"2026-04-22T13:13:23.097964Z","end":"2026-04-22T13:13:24.363607Z","steps":["trace[2129060049] 'process raft request' (duration: 578.924296ms)","trace[2129060049] 'compare' (duration: 685.839722ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:24.363694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:23.097948Z","time spent":"1.265714415s","remote":"127.0.0.1:35030","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-22T13:13:24.363892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18512576s","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-22T13:13:24.363929Z","caller":"traceutil/trace.go:171","msg":"trace[2102386018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1173; }","duration":"1.18518547s","start":"2026-04-22T13:13:23.178734Z","end":"2026-04-22T13:13:24.363919Z","steps":["trace[2102386018] 'agreement among raft nodes before linearized reading' (duration: 1.185067287s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:24.363957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:23.178718Z","time spent":"1.185232821s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T13:13:24.364017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125543205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-22T13:13:24.364068Z","caller":"traceutil/trace.go:171","msg":"trace[119086812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1173; }","duration":"1.125648617s","start":"2026-04-22T13:13:23.238408Z","end":"2026-04-22T13:13:24.364056Z","steps":["trace[119086812] 'agreement among raft nodes before linearized reading' (duration: 1.125462623s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:24.364097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:23.238395Z","time spent":"1.125695739s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T13:13:24.36424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.784371ms","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-22T13:13:24.364262Z","caller":"traceutil/trace.go:171","msg":"trace[1511084721] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1173; }","duration":"621.806922ms","start":"2026-04-22T13:13:23.742447Z","end":"2026-04-22T13:13:24.364254Z","steps":["trace[1511084721] 'agreement among raft nodes before linearized reading' (duration: 621.762631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:24.36428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:23.742375Z","time spent":"621.900633ms","remote":"127.0.0.1:58594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-22T13:13:24.364318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116730257s","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-22T13:13:24.364355Z","caller":"traceutil/trace.go:171","msg":"trace[1911193889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1173; }","duration":"1.116777198s","start":"2026-04-22T13:13:23.247568Z","end":"2026-04-22T13:13:24.364345Z","steps":["trace[1911193889] 'agreement among raft nodes before linearized reading' (duration: 1.116676755s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:24.364388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:23.247562Z","time spent":"1.116819458s","remote":"127.0.0.1:35030","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-22T13:13:24.364468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125931834s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-22T13:13:24.364746Z","caller":"traceutil/trace.go:171","msg":"trace[124922439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1173; }","duration":"1.126250224s","start":"2026-04-22T13:13:23.238478Z","end":"2026-04-22T13:13:24.364729Z","steps":["trace[124922439] 'agreement among raft nodes before linearized reading' (duration: 1.125837222s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:24.364792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:23.238459Z","time spent":"1.126321266s","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T13:13:38.792659Z","caller":"traceutil/trace.go:171","msg":"trace[1645688024] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1282; }","duration":"322.892208ms","start":"2026-04-22T13:13:38.469746Z","end":"2026-04-22T13:13:38.792639Z","steps":["trace[1645688024] 'read index received' (duration: 18.015337ms)","trace[1645688024] 'applied index is now lower than readState.Index' (duration: 304.876011ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:38.79277Z","caller":"traceutil/trace.go:171","msg":"trace[1171403701] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"329.484734ms","start":"2026-04-22T13:13:38.463273Z","end":"2026-04-22T13:13:38.792757Z","steps":["trace[1171403701] 'process raft request' (duration: 327.103297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:38.792849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.058188ms","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-22T13:13:38.792879Z","caller":"traceutil/trace.go:171","msg":"trace[1267804548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1222; }","duration":"321.113848ms","start":"2026-04-22T13:13:38.471755Z","end":"2026-04-22T13:13:38.792869Z","steps":["trace[1267804548] 'agreement among raft nodes before linearized reading' (duration: 321.014685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:38.792892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.141874ms","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-22T13:13:38.792901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.471743Z","time spent":"321.152329ms","remote":"127.0.0.1:35030","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-22T13:13:38.792919Z","caller":"traceutil/trace.go:171","msg":"trace[2056247278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1222; }","duration":"323.169995ms","start":"2026-04-22T13:13:38.469741Z","end":"2026-04-22T13:13:38.792911Z","steps":["trace[2056247278] 'agreement among raft nodes before linearized reading' (duration: 323.098834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:38.792911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.46326Z","time spent":"329.551145ms","remote":"127.0.0.1:35030","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-22T13:13:38.792936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:38.469702Z","time spent":"323.229688ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T13:13:40.636124Z","caller":"traceutil/trace.go:171","msg":"trace[1911374496] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1287; }","duration":"145.078568ms","start":"2026-04-22T13:13:40.491022Z","end":"2026-04-22T13:13:40.6361Z","steps":["trace[1911374496] 'read index received' (duration: 144.873663ms)","trace[1911374496] 'applied index is now lower than readState.Index' (duration: 203.825µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:40.636363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.332475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T13:13:40.636398Z","caller":"traceutil/trace.go:171","msg":"trace[1147257238] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1226; }","duration":"145.417868ms","start":"2026-04-22T13:13:40.490971Z","end":"2026-04-22T13:13:40.636389Z","steps":["trace[1147257238] 'agreement among raft nodes before linearized reading' (duration: 145.216062ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:40.636528Z","caller":"traceutil/trace.go:171","msg":"trace[660033257] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"185.259378ms","start":"2026-04-22T13:13:40.451235Z","end":"2026-04-22T13:13:40.636494Z","steps":["trace[660033257] 'process raft request' (duration: 184.729073ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:41.135732Z","caller":"traceutil/trace.go:171","msg":"trace[515950075] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"332.94572ms","start":"2026-04-22T13:13:40.802767Z","end":"2026-04-22T13:13:41.135713Z","steps":["trace[515950075] 'read index received' (duration: 332.909299ms)","trace[515950075] 'applied index is now lower than readState.Index' (duration: 35.571µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:41.135856Z","caller":"traceutil/trace.go:171","msg":"trace[1968319250] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"334.461083ms","start":"2026-04-22T13:13:40.801356Z","end":"2026-04-22T13:13:41.135818Z","steps":["trace[1968319250] 'process raft request' (duration: 334.246337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:41.135888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.109055ms","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-22T13:13:41.136019Z","caller":"traceutil/trace.go:171","msg":"trace[1811989739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1228; }","duration":"333.264829ms","start":"2026-04-22T13:13:40.802743Z","end":"2026-04-22T13:13:41.136007Z","steps":["trace[1811989739] 'agreement among raft nodes before linearized reading' (duration: 333.060783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:41.136051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:40.802731Z","time spent":"333.309991ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T13:13:41.136058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:40.801345Z","time spent":"334.583126ms","remote":"127.0.0.1:35030","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-22T13:13:41.306504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.674012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:13:41.30667Z","caller":"traceutil/trace.go:171","msg":"trace[2105231205] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"167.214441ms","start":"2026-04-22T13:13:41.139439Z","end":"2026-04-22T13:13:41.306653Z","steps":["trace[2105231205] 'process raft request' (duration: 167.17987ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:41.306856Z","caller":"traceutil/trace.go:171","msg":"trace[675906146] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"202.894925ms","start":"2026-04-22T13:13:41.103951Z","end":"2026-04-22T13:13:41.306846Z","steps":["trace[675906146] 'process raft request' (duration: 202.622368ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:41.306826Z","caller":"traceutil/trace.go:171","msg":"trace[1318404312] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"501.643043ms","start":"2026-04-22T13:13:40.805151Z","end":"2026-04-22T13:13:41.306794Z","steps":["trace[1318404312] 'process raft request' (duration: 395.621473ms)","trace[1318404312] 'compare' (duration: 105.575778ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:41.306934Z","caller":"traceutil/trace.go:171","msg":"trace[1681206564] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1290; }","duration":"171.141011ms","start":"2026-04-22T13:13:41.135783Z","end":"2026-04-22T13:13:41.306924Z","steps":["trace[1681206564] 'read index received' (duration: 65.000558ms)","trace[1681206564] 'applied index is now lower than readState.Index' (duration: 106.139303ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:41.306982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.040906ms","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":"warn","ts":"2026-04-22T13:13:41.30697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:40.805134Z","time spent":"501.763227ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:41.307006Z","caller":"traceutil/trace.go:171","msg":"trace[329417916] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1231; }","duration":"443.097718ms","start":"2026-04-22T13:13:40.863901Z","end":"2026-04-22T13:13:41.306999Z","steps":["trace[329417916] 'agreement among raft nodes before linearized reading' (duration: 443.053896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:41.307032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:40.863883Z","time spent":"443.140949ms","remote":"127.0.0.1:39792","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-22T13:13:41.307069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.476165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T13:13:41.307113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.711109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-22T13:13:41.307188Z","caller":"traceutil/trace.go:171","msg":"trace[924920766] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:1231; }","duration":"192.80461ms","start":"2026-04-22T13:13:41.114365Z","end":"2026-04-22T13:13:41.30717Z","steps":["trace[924920766] 'agreement among raft nodes before linearized reading' (duration: 192.584344ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:41.30713Z","caller":"traceutil/trace.go:171","msg":"trace[684403124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1231; }","duration":"222.589618ms","start":"2026-04-22T13:13:41.084527Z","end":"2026-04-22T13:13:41.307116Z","steps":["trace[684403124] 'agreement among raft nodes before linearized reading' (duration: 222.454975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:41.796766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.598655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-22T13:13:41.796828Z","caller":"traceutil/trace.go:171","msg":"trace[2088272308] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1232; }","duration":"287.683437ms","start":"2026-04-22T13:13:41.509131Z","end":"2026-04-22T13:13:41.796815Z","steps":["trace[2088272308] 'range keys from in-memory index tree' (duration: 287.539254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:41.797193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.328414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:108690"} {"level":"info","ts":"2026-04-22T13:13:41.79722Z","caller":"traceutil/trace.go:171","msg":"trace[606453552] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1232; }","duration":"289.383166ms","start":"2026-04-22T13:13:41.50783Z","end":"2026-04-22T13:13:41.797213Z","steps":["trace[606453552] 'range keys from in-memory index tree' (duration: 288.990605ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:43.011773Z","caller":"traceutil/trace.go:171","msg":"trace[1105411151] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1295; }","duration":"285.320182ms","start":"2026-04-22T13:13:42.72643Z","end":"2026-04-22T13:13:43.01175Z","steps":["trace[1105411151] 'read index received' (duration: 285.125647ms)","trace[1105411151] 'applied index is now lower than readState.Index' (duration: 193.255µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:43.011877Z","caller":"traceutil/trace.go:171","msg":"trace[40215640] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"367.324327ms","start":"2026-04-22T13:13:42.64453Z","end":"2026-04-22T13:13:43.011854Z","steps":["trace[40215640] 'process raft request' (duration: 367.06028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:43.011953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.512475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:43.011987Z","caller":"traceutil/trace.go:171","msg":"trace[895474268] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1233; }","duration":"217.596297ms","start":"2026-04-22T13:13:42.794381Z","end":"2026-04-22T13:13:43.011977Z","steps":["trace[895474268] 'agreement among raft nodes before linearized reading' (duration: 217.514086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:43.012036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:42.644504Z","time spent":"367.42797ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:13:43.012095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.61915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-22T13:13:43.012119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.288962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T13:13:43.012189Z","caller":"traceutil/trace.go:171","msg":"trace[1443786322] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1233; }","duration":"210.409685ms","start":"2026-04-22T13:13:42.801768Z","end":"2026-04-22T13:13:43.012178Z","steps":["trace[1443786322] 'agreement among raft nodes before linearized reading' (duration: 210.293802ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:43.012188Z","caller":"traceutil/trace.go:171","msg":"trace[1150350623] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1233; }","duration":"285.798846ms","start":"2026-04-22T13:13:42.72637Z","end":"2026-04-22T13:13:43.012169Z","steps":["trace[1150350623] 'agreement among raft nodes before linearized reading' (duration: 285.511178ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:43.205836Z","caller":"traceutil/trace.go:171","msg":"trace[304188418] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"186.248166ms","start":"2026-04-22T13:13:43.019567Z","end":"2026-04-22T13:13:43.205815Z","steps":["trace[304188418] 'process raft request' (duration: 163.198558ms)","trace[304188418] 'compare' (duration: 22.972636ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:43.257597Z","caller":"traceutil/trace.go:171","msg":"trace[1037774414] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"233.499855ms","start":"2026-04-22T13:13:43.024084Z","end":"2026-04-22T13:13:43.257584Z","steps":["trace[1037774414] 'process raft request' (duration: 233.295799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:43.257762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.49941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:43.257846Z","caller":"traceutil/trace.go:171","msg":"trace[935660966] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1235; }","duration":"161.614744ms","start":"2026-04-22T13:13:43.096192Z","end":"2026-04-22T13:13:43.257807Z","steps":["trace[935660966] 'agreement among raft nodes before linearized reading' (duration: 161.46929ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:43.257548Z","caller":"traceutil/trace.go:171","msg":"trace[1509656092] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1296; }","duration":"161.302565ms","start":"2026-04-22T13:13:43.096223Z","end":"2026-04-22T13:13:43.257526Z","steps":["trace[1509656092] 'read index received' (duration: 86.595195ms)","trace[1509656092] 'applied index is now lower than readState.Index' (duration: 74.70653ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:43.258359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.657546ms","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-22T13:13:43.258416Z","caller":"traceutil/trace.go:171","msg":"trace[1538447998] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1235; }","duration":"118.738069ms","start":"2026-04-22T13:13:43.139664Z","end":"2026-04-22T13:13:43.258402Z","steps":["trace[1538447998] 'agreement among raft nodes before linearized reading' (duration: 118.43808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:43.43319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.057906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:13:43.433371Z","caller":"traceutil/trace.go:171","msg":"trace[394650675] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"171.887283ms","start":"2026-04-22T13:13:43.261459Z","end":"2026-04-22T13:13:43.433347Z","steps":["trace[394650675] 'process raft request' (duration: 62.58333ms)","trace[394650675] 'compare' (duration: 108.955143ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:43.433468Z","caller":"traceutil/trace.go:171","msg":"trace[1415392596] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1298; }","duration":"122.027151ms","start":"2026-04-22T13:13:43.311426Z","end":"2026-04-22T13:13:43.433453Z","steps":["trace[1415392596] 'read index received' (duration: 12.629785ms)","trace[1415392596] 'applied index is now lower than readState.Index' (duration: 109.393796ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:43.433644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.211006ms","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-22T13:13:43.433672Z","caller":"traceutil/trace.go:171","msg":"trace[678574015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1236; }","duration":"122.37401ms","start":"2026-04-22T13:13:43.311289Z","end":"2026-04-22T13:13:43.433663Z","steps":["trace[678574015] 'agreement among raft nodes before linearized reading' (duration: 122.253586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:43.433975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.361589ms","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-22T13:13:43.434014Z","caller":"traceutil/trace.go:171","msg":"trace[1350913707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1237; }","duration":"122.431382ms","start":"2026-04-22T13:13:43.311574Z","end":"2026-04-22T13:13:43.434005Z","steps":["trace[1350913707] 'agreement among raft nodes before linearized reading' (duration: 122.317689ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:43.43415Z","caller":"traceutil/trace.go:171","msg":"trace[1997322944] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"121.982279ms","start":"2026-04-22T13:13:43.312158Z","end":"2026-04-22T13:13:43.43414Z","steps":["trace[1997322944] 'process raft request' (duration: 121.246069ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:44.38343Z","caller":"traceutil/trace.go:171","msg":"trace[459535616] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1301; }","duration":"476.830556ms","start":"2026-04-22T13:13:43.906585Z","end":"2026-04-22T13:13:44.383415Z","steps":["trace[459535616] 'read index received' (duration: 476.694133ms)","trace[459535616] 'applied index is now lower than readState.Index' (duration: 135.893µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:44.383504Z","caller":"traceutil/trace.go:171","msg":"trace[107513726] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"945.75664ms","start":"2026-04-22T13:13:43.437742Z","end":"2026-04-22T13:13:44.383498Z","steps":["trace[107513726] 'process raft request' (duration: 945.574224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:44.383573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:43.43772Z","time spent":"945.80069ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:13:44.383669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.098533ms","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-22T13:13:44.383692Z","caller":"traceutil/trace.go:171","msg":"trace[1600897322] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1239; }","duration":"477.121003ms","start":"2026-04-22T13:13:43.906564Z","end":"2026-04-22T13:13:44.383685Z","steps":["trace[1600897322] 'agreement among raft nodes before linearized reading' (duration: 477.087103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:44.383708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:43.9065Z","time spent":"477.204206ms","remote":"127.0.0.1:40534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-04-22T13:13:44.383803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.402027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:44.383854Z","caller":"traceutil/trace.go:171","msg":"trace[723817646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1239; }","duration":"299.481199ms","start":"2026-04-22T13:13:44.084361Z","end":"2026-04-22T13:13:44.383842Z","steps":["trace[723817646] 'agreement among raft nodes before linearized reading' (duration: 299.396157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:45.470187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.052492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T13:13:45.470285Z","caller":"traceutil/trace.go:171","msg":"trace[1781800808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1240; }","duration":"260.198075ms","start":"2026-04-22T13:13:45.210064Z","end":"2026-04-22T13:13:45.470262Z","steps":["trace[1781800808] 'range keys from in-memory index tree' (duration: 259.861146ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:45.716688Z","caller":"traceutil/trace.go:171","msg":"trace[1203754669] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1304; }","duration":"205.372104ms","start":"2026-04-22T13:13:45.511298Z","end":"2026-04-22T13:13:45.71667Z","steps":["trace[1203754669] 'read index received' (duration: 168.113216ms)","trace[1203754669] 'applied index is now lower than readState.Index' (duration: 37.258288ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:45.716778Z","caller":"traceutil/trace.go:171","msg":"trace[1877082462] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"242.126648ms","start":"2026-04-22T13:13:45.474643Z","end":"2026-04-22T13:13:45.71677Z","steps":["trace[1877082462] 'process raft request' (duration: 241.924153ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:45.716873Z","caller":"traceutil/trace.go:171","msg":"trace[749683679] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"242.261981ms","start":"2026-04-22T13:13:45.474572Z","end":"2026-04-22T13:13:45.716834Z","steps":["trace[749683679] 'process raft request' (duration: 204.753776ms)","trace[749683679] 'compare' (duration: 36.932169ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:45.717196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.903729ms","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-22T13:13:45.717227Z","caller":"traceutil/trace.go:171","msg":"trace[851933418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1244; }","duration":"205.989901ms","start":"2026-04-22T13:13:45.511229Z","end":"2026-04-22T13:13:45.717219Z","steps":["trace[851933418] 'agreement among raft nodes before linearized reading' (duration: 205.521918ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:13:45.94372Z","caller":"traceutil/trace.go:171","msg":"trace[341637290] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"145.596713ms","start":"2026-04-22T13:13:45.798102Z","end":"2026-04-22T13:13:45.943698Z","steps":["trace[341637290] 'process raft request' (duration: 118.939573ms)","trace[341637290] 'compare' (duration: 26.419064ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:53.458999Z","caller":"traceutil/trace.go:171","msg":"trace[98509136] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"268.300342ms","start":"2026-04-22T13:13:53.190668Z","end":"2026-04-22T13:13:53.458968Z","steps":["trace[98509136] 'process raft request' (duration: 267.88006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:54.254967Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764890972920504,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T13:13:54.43963Z","caller":"traceutil/trace.go:171","msg":"trace[740737338] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1393; }","duration":"684.746401ms","start":"2026-04-22T13:13:53.754864Z","end":"2026-04-22T13:13:54.43961Z","steps":["trace[740737338] 'read index received' (duration: 684.566055ms)","trace[740737338] 'applied index is now lower than readState.Index' (duration: 179.436µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:54.439696Z","caller":"traceutil/trace.go:171","msg":"trace[667501321] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"689.326278ms","start":"2026-04-22T13:13:53.750354Z","end":"2026-04-22T13:13:54.43968Z","steps":["trace[667501321] 'process raft request' (duration: 689.115032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:54.439871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.988867ms","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-22T13:13:54.440035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.110103ms","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-22T13:13:54.440079Z","caller":"traceutil/trace.go:171","msg":"trace[1477143201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1329; }","duration":"652.217875ms","start":"2026-04-22T13:13:53.787847Z","end":"2026-04-22T13:13:54.440065Z","steps":["trace[1477143201] 'agreement among raft nodes before linearized reading' (duration: 652.074031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:54.440108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:53.787836Z","time spent":"652.264277ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T13:13:54.440159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.142576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:13:54.440284Z","caller":"traceutil/trace.go:171","msg":"trace[1142033570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1329; }","duration":"354.307561ms","start":"2026-04-22T13:13:54.085949Z","end":"2026-04-22T13:13:54.440257Z","steps":["trace[1142033570] 'agreement among raft nodes before linearized reading' (duration: 354.095405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:54.440344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:54.08593Z","time spent":"354.396842ms","remote":"127.0.0.1:34700","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-22T13:13:54.440301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:53.750342Z","time spent":"689.610547ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:13:54.440628Z","caller":"traceutil/trace.go:171","msg":"trace[1719038603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1329; }","duration":"685.100411ms","start":"2026-04-22T13:13:53.754827Z","end":"2026-04-22T13:13:54.439928Z","steps":["trace[1719038603] 'agreement among raft nodes before linearized reading' (duration: 684.880355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:54.440701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:53.754818Z","time spent":"685.863822ms","remote":"127.0.0.1:35030","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-22T13:13:59.938247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.907245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:13:59.938351Z","caller":"traceutil/trace.go:171","msg":"trace[1952162972] linearizableReadLoop","detail":"{readStateIndex:1421; appliedIndex:1420; }","duration":"188.790978ms","start":"2026-04-22T13:13:59.749542Z","end":"2026-04-22T13:13:59.938333Z","steps":["trace[1952162972] 'read index received' (duration: 44.689647ms)","trace[1952162972] 'applied index is now lower than readState.Index' (duration: 144.099891ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:13:59.93837Z","caller":"traceutil/trace.go:171","msg":"trace[157047029] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"410.011007ms","start":"2026-04-22T13:13:59.528326Z","end":"2026-04-22T13:13:59.938337Z","steps":["trace[157047029] 'process raft request' (duration: 265.793313ms)","trace[157047029] 'compare' (duration: 143.524085ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:13:59.938677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.138957ms","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-22T13:13:59.938756Z","caller":"traceutil/trace.go:171","msg":"trace[759291552] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1355; }","duration":"189.197269ms","start":"2026-04-22T13:13:59.749497Z","end":"2026-04-22T13:13:59.938695Z","steps":["trace[759291552] 'agreement among raft nodes before linearized reading' (duration: 188.88088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:59.938757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:59.5283Z","time spent":"410.15428ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:15:23.430274Z","caller":"traceutil/trace.go:171","msg":"trace[1887897170] linearizableReadLoop","detail":"{readStateIndex:1823; appliedIndex:1820; }","duration":"296.208737ms","start":"2026-04-22T13:15:23.134043Z","end":"2026-04-22T13:15:23.430252Z","steps":["trace[1887897170] 'read index received' (duration: 45.434624ms)","trace[1887897170] 'applied index is now lower than readState.Index' (duration: 250.772983ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:15:23.430416Z","caller":"traceutil/trace.go:171","msg":"trace[1194193426] transaction","detail":"{read_only:false; response_revision:1739; number_of_response:1; }","duration":"319.132548ms","start":"2026-04-22T13:15:23.111251Z","end":"2026-04-22T13:15:23.430383Z","steps":["trace[1194193426] 'process raft request' (duration: 318.808999ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:15:23.430487Z","caller":"traceutil/trace.go:171","msg":"trace[915972208] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"316.658951ms","start":"2026-04-22T13:15:23.113818Z","end":"2026-04-22T13:15:23.430477Z","steps":["trace[915972208] 'process raft request' (duration: 316.391233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:15:23.430567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:15:23.111233Z","time spent":"319.261593ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:15:23.430606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:15:23.113809Z","time spent":"316.738812ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:15:23.430441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.38042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T13:15:23.430688Z","caller":"traceutil/trace.go:171","msg":"trace[1048699196] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1740; }","duration":"296.686729ms","start":"2026-04-22T13:15:23.133992Z","end":"2026-04-22T13:15:23.430679Z","steps":["trace[1048699196] 'agreement among raft nodes before linearized reading' (duration: 296.365231ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:15:53.496771Z","caller":"traceutil/trace.go:171","msg":"trace[1261027817] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1964; }","duration":"126.999012ms","start":"2026-04-22T13:15:53.369744Z","end":"2026-04-22T13:15:53.496743Z","steps":["trace[1261027817] 'read index received' (duration: 126.753744ms)","trace[1261027817] 'applied index is now lower than readState.Index' (duration: 244.078µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:15:53.496882Z","caller":"traceutil/trace.go:171","msg":"trace[1792414897] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"206.702028ms","start":"2026-04-22T13:15:53.290146Z","end":"2026-04-22T13:15:53.496848Z","steps":["trace[1792414897] 'process raft request' (duration: 206.42698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:15:53.497073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.305833ms","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-22T13:15:53.497166Z","caller":"traceutil/trace.go:171","msg":"trace[456313536] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1876; }","duration":"127.452086ms","start":"2026-04-22T13:15:53.369702Z","end":"2026-04-22T13:15:53.497154Z","steps":["trace[456313536] 'agreement among raft nodes before linearized reading' (duration: 127.23169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:15:53.77594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.759026ms","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-22T13:15:53.776054Z","caller":"traceutil/trace.go:171","msg":"trace[2059807075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1877; }","duration":"155.915152ms","start":"2026-04-22T13:15:53.620113Z","end":"2026-04-22T13:15:53.776028Z","steps":["trace[2059807075] 'range keys from in-memory index tree' (duration: 155.55952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:15:53.776188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.203006ms","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-22T13:15:53.77628Z","caller":"traceutil/trace.go:171","msg":"trace[1602516492] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1877; }","duration":"122.345071ms","start":"2026-04-22T13:15:53.653915Z","end":"2026-04-22T13:15:53.77626Z","steps":["trace[1602516492] 'range keys from in-memory index tree' (duration: 121.99473ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:17:36.486982Z","caller":"traceutil/trace.go:171","msg":"trace[1977429204] transaction","detail":"{read_only:false; response_revision:2386; number_of_response:1; }","duration":"101.723161ms","start":"2026-04-22T13:17:36.38522Z","end":"2026-04-22T13:17:36.486943Z","steps":["trace[1977429204] 'process raft request' (duration: 39.181637ms)","trace[1977429204] 'compare' (duration: 62.044781ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:17:48.626433Z","caller":"traceutil/trace.go:171","msg":"trace[566994763] linearizableReadLoop","detail":"{readStateIndex:2554; appliedIndex:2553; }","duration":"131.495057ms","start":"2026-04-22T13:17:48.494904Z","end":"2026-04-22T13:17:48.626399Z","steps":["trace[566994763] 'read index received' (duration: 130.268933ms)","trace[566994763] 'applied index is now lower than readState.Index' (duration: 1.224774ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:17:48.626609Z","caller":"traceutil/trace.go:171","msg":"trace[1234612057] transaction","detail":"{read_only:false; response_revision:2440; number_of_response:1; }","duration":"162.352943ms","start":"2026-04-22T13:17:48.464183Z","end":"2026-04-22T13:17:48.626536Z","steps":["trace[1234612057] 'process raft request' (duration: 161.127659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:17:48.626732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.761706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:1 size:220"} {"level":"warn","ts":"2026-04-22T13:17:48.626784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.859837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/sh.helm.release.v1.ceph-csi-rbd.v1\" ","response":"range_response_count:1 size:32864"} {"level":"info","ts":"2026-04-22T13:17:48.626822Z","caller":"traceutil/trace.go:171","msg":"trace[1411953499] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:1; response_revision:2440; }","duration":"122.914909ms","start":"2026-04-22T13:17:48.503887Z","end":"2026-04-22T13:17:48.626802Z","steps":["trace[1411953499] 'agreement among raft nodes before linearized reading' (duration: 122.739084ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:17:48.626848Z","caller":"traceutil/trace.go:171","msg":"trace[1085549902] range","detail":"{range_begin:/registry/secrets/kube-system/sh.helm.release.v1.ceph-csi-rbd.v1; range_end:; response_count:1; response_revision:2440; }","duration":"131.97751ms","start":"2026-04-22T13:17:48.494852Z","end":"2026-04-22T13:17:48.62683Z","steps":["trace[1085549902] 'agreement among raft nodes before linearized reading' (duration: 131.735704ms)"],"step_count":1}