{"level":"warn","ts":"2026-04-28T17:24:23.949371Z","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-28T17:24:23.949468Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.21:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.21:2380","--initial-cluster=instance=https://199.19.213.21:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.21:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.21: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-28T17:24:23.949534Z","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-28T17:24:23.949545Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.21:2380"]} {"level":"info","ts":"2026-04-28T17:24:23.94956Z","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-28T17:24:23.950406Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"]} {"level":"info","ts":"2026-04-28T17:24:23.950482Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.21:2380"],"listen-peer-urls":["https://199.19.213.21:2380"],"advertise-client-urls":["https://199.19.213.21:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.21: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-28T17:24:23.954509Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.832527ms"} {"level":"info","ts":"2026-04-28T17:24:23.960763Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c39f03fd9ad57fdc","cluster-id":"3c95a04d00bbaaa4"} {"level":"info","ts":"2026-04-28T17:24:23.960884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T17:24:23.960914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became follower at term 0"} {"level":"info","ts":"2026-04-28T17:24:23.960948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c39f03fd9ad57fdc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T17:24:23.960979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became follower at term 1"} {"level":"info","ts":"2026-04-28T17:24:23.961019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=(14095989746452234204)"} {"level":"warn","ts":"2026-04-28T17:24:23.967178Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T17:24:23.971025Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T17:24:23.972999Z","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-28T17:24:23.976074Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c39f03fd9ad57fdc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T17:24:23.976271Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c39f03fd9ad57fdc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T17:24:23.976471Z","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-28T17:24:23.976542Z","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-28T17:24:23.976565Z","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-28T17:24:23.977315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=(14095989746452234204)"} {"level":"info","ts":"2026-04-28T17:24:23.977554Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3c95a04d00bbaaa4","local-member-id":"c39f03fd9ad57fdc","added-peer-id":"c39f03fd9ad57fdc","added-peer-peer-urls":["https://199.19.213.21:2380"]} {"level":"info","ts":"2026-04-28T17:24:23.980056Z","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-28T17:24:23.980217Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c39f03fd9ad57fdc","initial-advertise-peer-urls":["https://199.19.213.21:2380"],"listen-peer-urls":["https://199.19.213.21:2380"],"advertise-client-urls":["https://199.19.213.21:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T17:24:23.980241Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T17:24:23.980321Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.21:2380"} {"level":"info","ts":"2026-04-28T17:24:23.980333Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.21:2380"} {"level":"info","ts":"2026-04-28T17:24:24.861476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T17:24:24.861534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T17:24:24.861572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc received MsgPreVoteResp from c39f03fd9ad57fdc at term 1"} {"level":"info","ts":"2026-04-28T17:24:24.86159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became candidate at term 2"} {"level":"info","ts":"2026-04-28T17:24:24.861607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc received MsgVoteResp from c39f03fd9ad57fdc at term 2"} {"level":"info","ts":"2026-04-28T17:24:24.861628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became leader at term 2"} {"level":"info","ts":"2026-04-28T17:24:24.861646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c39f03fd9ad57fdc elected leader c39f03fd9ad57fdc at term 2"} {"level":"info","ts":"2026-04-28T17:24:24.862746Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c39f03fd9ad57fdc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.21:2379]}","request-path":"/0/members/c39f03fd9ad57fdc/attributes","cluster-id":"3c95a04d00bbaaa4","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T17:24:24.862922Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T17:24:24.862987Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T17:24:24.863096Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T17:24:24.863924Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T17:24:24.863974Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T17:24:24.865119Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3c95a04d00bbaaa4","local-member-id":"c39f03fd9ad57fdc","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T17:24:24.865224Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T17:24:24.865254Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T17:24:24.866709Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.21:2379"} {"level":"info","ts":"2026-04-28T17:24:24.86773Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-28T17:24:35.419342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.681676ms","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-28T17:24:35.41948Z","caller":"traceutil/trace.go:171","msg":"trace[1777418992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:350; }","duration":"123.869041ms","start":"2026-04-28T17:24:35.295587Z","end":"2026-04-28T17:24:35.419456Z","steps":["trace[1777418992] 'range keys from in-memory index tree' (duration: 123.561573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:24:41.492055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213412476364856413,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T17:24:41.993076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213412476364856413,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T17:24:42.494199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213412476364856413,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T17:24:42.992243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000416563s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-28T17:24:42.992396Z","caller":"traceutil/trace.go:171","msg":"trace[890385561] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000607348s","start":"2026-04-28T17:24:40.991769Z","end":"2026-04-28T17:24:42.992377Z","steps":["trace[890385561] 'agreement among raft nodes before linearized reading' (duration: 2.000439534s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:24:42.992474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:24:40.991753Z","time spent":"2.00070791s","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/28 17:24:42 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-28T17:24:42.994335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213412476364856413,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T17:24:43.29672Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.459059617s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T17:24:43.297173Z","caller":"traceutil/trace.go:171","msg":"trace[1224712043] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"2.459637133s","start":"2026-04-28T17:24:40.837516Z","end":"2026-04-28T17:24:43.297153Z","steps":["trace[1224712043] 'process raft request' (duration: 2.459463139s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:24:43.297587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:24:40.837502Z","time spent":"2.459724205s","remote":"127.0.0.1:41562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T17:24:43.359207Z","caller":"traceutil/trace.go:171","msg":"trace[1983779891] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:383; }","duration":"2.367384028s","start":"2026-04-28T17:24:40.991804Z","end":"2026-04-28T17:24:43.359188Z","steps":["trace[1983779891] 'read index received' (duration: 2.305214042s)","trace[1983779891] 'applied index is now lower than readState.Index' (duration: 62.169026ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:24:43.35937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014542732s","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-28T17:24:43.359403Z","caller":"traceutil/trace.go:171","msg":"trace[34900015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:375; }","duration":"1.014629965s","start":"2026-04-28T17:24:42.344765Z","end":"2026-04-28T17:24:43.359395Z","steps":["trace[34900015] 'agreement among raft nodes before linearized reading' (duration: 1.014550593s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:24:43.359445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:24:42.344743Z","time spent":"1.014695177s","remote":"127.0.0.1:41660","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-28T17:24:43.359494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.886447116s","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-28T17:24:43.359672Z","caller":"traceutil/trace.go:171","msg":"trace[2066093229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:375; }","duration":"1.88666441s","start":"2026-04-28T17:24:41.472991Z","end":"2026-04-28T17:24:43.359656Z","steps":["trace[2066093229] 'agreement among raft nodes before linearized reading' (duration: 1.886320442s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:24:43.359714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:24:41.472972Z","time spent":"1.886727942s","remote":"127.0.0.1:41660","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-28T17:24:43.359763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.483729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:24:43.359784Z","caller":"traceutil/trace.go:171","msg":"trace[1986252026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:375; }","duration":"367.53895ms","start":"2026-04-28T17:24:42.992239Z","end":"2026-04-28T17:24:43.359778Z","steps":["trace[1986252026] 'agreement among raft nodes before linearized reading' (duration: 367.506559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:24:43.35981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:24:42.992222Z","time spent":"367.582571ms","remote":"127.0.0.1:41378","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-28T17:24:43.360083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.576980067s","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-28T17:24:43.360111Z","caller":"traceutil/trace.go:171","msg":"trace[627744252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:375; }","duration":"1.577048798s","start":"2026-04-28T17:24:41.783056Z","end":"2026-04-28T17:24:43.360105Z","steps":["trace[627744252] 'agreement among raft nodes before linearized reading' (duration: 1.576992647s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:24:43.360131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:24:41.783034Z","time spent":"1.57709201s","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T17:24:43.36021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.791852321s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T17:24:43.360354Z","caller":"traceutil/trace.go:171","msg":"trace[1370228178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:375; }","duration":"1.792001245s","start":"2026-04-28T17:24:41.568322Z","end":"2026-04-28T17:24:43.360323Z","steps":["trace[1370228178] 'agreement among raft nodes before linearized reading' (duration: 1.791736668s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:24:43.360408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:24:41.568271Z","time spent":"1.792124628s","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T17:25:03.997679Z","caller":"traceutil/trace.go:171","msg":"trace[976145898] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"506.590169ms","start":"2026-04-28T17:25:03.491064Z","end":"2026-04-28T17:25:03.997655Z","steps":["trace[976145898] 'process raft request' (duration: 506.394714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:03.997861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:03.491044Z","time spent":"506.710813ms","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T17:25:04.002503Z","caller":"traceutil/trace.go:171","msg":"trace[855409535] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:502; }","duration":"488.350334ms","start":"2026-04-28T17:25:03.514141Z","end":"2026-04-28T17:25:04.002492Z","steps":["trace[855409535] 'read index received' (duration: 483.282904ms)","trace[855409535] 'applied index is now lower than readState.Index' (duration: 5.06691ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:25:04.002714Z","caller":"traceutil/trace.go:171","msg":"trace[1857122411] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"491.276949ms","start":"2026-04-28T17:25:03.511405Z","end":"2026-04-28T17:25:04.002682Z","steps":["trace[1857122411] 'process raft request' (duration: 490.813387ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:04.002743Z","caller":"traceutil/trace.go:171","msg":"trace[1316900002] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"504.563137ms","start":"2026-04-28T17:25:03.498145Z","end":"2026-04-28T17:25:04.002708Z","steps":["trace[1316900002] 'process raft request' (duration: 503.907951ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:04.002785Z","caller":"traceutil/trace.go:171","msg":"trace[1471477021] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"492.354086ms","start":"2026-04-28T17:25:03.51042Z","end":"2026-04-28T17:25:04.002774Z","steps":["trace[1471477021] 'process raft request' (duration: 491.770151ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:04.00277Z","caller":"traceutil/trace.go:171","msg":"trace[1118169385] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"497.352974ms","start":"2026-04-28T17:25:03.505377Z","end":"2026-04-28T17:25:04.00273Z","steps":["trace[1118169385] 'process raft request' (duration: 496.705838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:04.002842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:03.498115Z","time spent":"504.67768ms","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T17:25:04.002854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:03.511384Z","time spent":"491.402531ms","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T17:25:04.002909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:03.505335Z","time spent":"497.506718ms","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T17:25:04.002732Z","caller":"traceutil/trace.go:171","msg":"trace[1780905593] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"493.873734ms","start":"2026-04-28T17:25:03.50884Z","end":"2026-04-28T17:25:04.002714Z","steps":["trace[1780905593] 'process raft request' (duration: 493.30466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:04.002932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:03.510408Z","time spent":"492.418507ms","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T17:25:04.002964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:03.508827Z","time spent":"494.120871ms","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T17:25:04.003001Z","caller":"traceutil/trace.go:171","msg":"trace[1469721996] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"452.318774ms","start":"2026-04-28T17:25:03.550668Z","end":"2026-04-28T17:25:04.002987Z","steps":["trace[1469721996] 'process raft request' (duration: 451.72868ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:04.003029Z","caller":"traceutil/trace.go:171","msg":"trace[982838982] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"319.439962ms","start":"2026-04-28T17:25:03.683582Z","end":"2026-04-28T17:25:04.003022Z","steps":["trace[982838982] 'process raft request' (duration: 318.863448ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:04.003031Z","caller":"traceutil/trace.go:171","msg":"trace[657761856] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"482.869413ms","start":"2026-04-28T17:25:03.520151Z","end":"2026-04-28T17:25:04.003021Z","steps":["trace[657761856] 'process raft request' (duration: 482.108504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:04.003051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:03.550647Z","time spent":"452.381445ms","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T17:25:04.003071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:03.520129Z","time spent":"482.923716ms","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T17:25:04.003085Z","caller":"traceutil/trace.go:171","msg":"trace[1912671996] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"482.519315ms","start":"2026-04-28T17:25:03.52056Z","end":"2026-04-28T17:25:04.00308Z","steps":["trace[1912671996] 'process raft request' (duration: 481.780396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:04.003055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:03.683551Z","time spent":"319.492114ms","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T17:25:04.003114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.963389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-28T17:25:04.003116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:03.52054Z","time spent":"482.563985ms","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T17:25:04.003147Z","caller":"traceutil/trace.go:171","msg":"trace[915472452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:499; }","duration":"489.025691ms","start":"2026-04-28T17:25:03.514113Z","end":"2026-04-28T17:25:04.003139Z","steps":["trace[915472452] 'agreement among raft nodes before linearized reading' (duration: 488.901018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:04.003173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:03.514102Z","time spent":"489.066572ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T17:25:04.003212Z","caller":"traceutil/trace.go:171","msg":"trace[1880061746] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"497.250701ms","start":"2026-04-28T17:25:03.505449Z","end":"2026-04-28T17:25:04.002699Z","steps":["trace[1880061746] 'process raft request' (duration: 496.660946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:04.003286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:03.505443Z","time spent":"497.789885ms","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T17:25:04.002672Z","caller":"traceutil/trace.go:171","msg":"trace[1911174816] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"504.609398ms","start":"2026-04-28T17:25:03.498053Z","end":"2026-04-28T17:25:04.002662Z","steps":["trace[1911174816] 'process raft request' (duration: 503.942311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:04.003419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:03.498031Z","time spent":"505.367968ms","remote":"127.0.0.1:41410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T17:25:04.01147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.378134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T17:25:04.011635Z","caller":"traceutil/trace.go:171","msg":"trace[1790728781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:500; }","duration":"412.57708ms","start":"2026-04-28T17:25:03.599048Z","end":"2026-04-28T17:25:04.011625Z","steps":["trace[1790728781] 'agreement among raft nodes before linearized reading' (duration: 412.378705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:04.011662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:03.599029Z","time spent":"412.62478ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T17:25:04.012173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.408463ms","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-28T17:25:04.012198Z","caller":"traceutil/trace.go:171","msg":"trace[1528438635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:500; }","duration":"390.474855ms","start":"2026-04-28T17:25:03.621716Z","end":"2026-04-28T17:25:04.012191Z","steps":["trace[1528438635] 'agreement among raft nodes before linearized reading' (duration: 390.430994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:04.012217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:03.621696Z","time spent":"390.517156ms","remote":"127.0.0.1:41660","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-28T17:25:04.01263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.490904ms","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-28T17:25:04.012659Z","caller":"traceutil/trace.go:171","msg":"trace[860212354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:500; }","duration":"395.542434ms","start":"2026-04-28T17:25:03.617109Z","end":"2026-04-28T17:25:04.012652Z","steps":["trace[860212354] 'agreement among raft nodes before linearized reading' (duration: 395.490233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:04.012685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:03.617099Z","time spent":"395.579846ms","remote":"127.0.0.1:41660","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-28T17:25:04.26491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.328661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:25:04.265033Z","caller":"traceutil/trace.go:171","msg":"trace[1373095445] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"210.918683ms","start":"2026-04-28T17:25:04.054094Z","end":"2026-04-28T17:25:04.265013Z","steps":["trace[1373095445] 'read index received' (duration: 911.283µs)","trace[1373095445] 'applied index is now lower than readState.Index' (duration: 210.00604ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:25:04.265168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.067526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:1019"} {"level":"info","ts":"2026-04-28T17:25:04.265203Z","caller":"traceutil/trace.go:171","msg":"trace[1361389832] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:515; }","duration":"211.121167ms","start":"2026-04-28T17:25:04.054072Z","end":"2026-04-28T17:25:04.265193Z","steps":["trace[1361389832] 'agreement among raft nodes before linearized reading' (duration: 210.991944ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:04.26531Z","caller":"traceutil/trace.go:171","msg":"trace[1122185925] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"214.588446ms","start":"2026-04-28T17:25:04.050671Z","end":"2026-04-28T17:25:04.265259Z","steps":["trace[1122185925] 'process raft request' (duration: 111.63523ms)","trace[1122185925] 'compare' (duration: 102.137306ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:25:04.266497Z","caller":"traceutil/trace.go:171","msg":"trace[1465570709] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"203.038202ms","start":"2026-04-28T17:25:04.063452Z","end":"2026-04-28T17:25:04.26649Z","steps":["trace[1465570709] 'process raft request' (duration: 202.699433ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:04.26672Z","caller":"traceutil/trace.go:171","msg":"trace[39485665] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"200.924478ms","start":"2026-04-28T17:25:04.065784Z","end":"2026-04-28T17:25:04.266708Z","steps":["trace[39485665] 'process raft request' (duration: 200.475746ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:04.26688Z","caller":"traceutil/trace.go:171","msg":"trace[606910715] transaction","detail":"{read_only:false; number_of_response:1; response_revision:517; }","duration":"188.383828ms","start":"2026-04-28T17:25:04.078489Z","end":"2026-04-28T17:25:04.266872Z","steps":["trace[606910715] 'process raft request' (duration: 187.912876ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:04.267682Z","caller":"traceutil/trace.go:171","msg":"trace[479137883] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"171.868456ms","start":"2026-04-28T17:25:04.095807Z","end":"2026-04-28T17:25:04.267675Z","steps":["trace[479137883] 'process raft request' (duration: 171.370493ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:04.268116Z","caller":"traceutil/trace.go:171","msg":"trace[1009700725] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"134.012419ms","start":"2026-04-28T17:25:04.134097Z","end":"2026-04-28T17:25:04.268109Z","steps":["trace[1009700725] 'process raft request' (duration: 133.931127ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:04.268297Z","caller":"traceutil/trace.go:171","msg":"trace[1381372999] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"169.775234ms","start":"2026-04-28T17:25:04.098517Z","end":"2026-04-28T17:25:04.268292Z","steps":["trace[1381372999] 'process raft request' (duration: 169.118346ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:04.436116Z","caller":"traceutil/trace.go:171","msg":"trace[1454765231] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"163.370099ms","start":"2026-04-28T17:25:04.272704Z","end":"2026-04-28T17:25:04.436074Z","steps":["trace[1454765231] 'process raft request' (duration: 112.268895ms)","trace[1454765231] 'compare' (duration: 50.887348ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:25:04.436161Z","caller":"traceutil/trace.go:171","msg":"trace[1046974810] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"158.339021ms","start":"2026-04-28T17:25:04.277807Z","end":"2026-04-28T17:25:04.436147Z","steps":["trace[1046974810] 'process raft request' (duration: 158.229288ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:04.436161Z","caller":"traceutil/trace.go:171","msg":"trace[1280305938] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"160.6567ms","start":"2026-04-28T17:25:04.275486Z","end":"2026-04-28T17:25:04.436142Z","steps":["trace[1280305938] 'process raft request' (duration: 160.512136ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:04.436401Z","caller":"traceutil/trace.go:171","msg":"trace[191426635] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"158.487005ms","start":"2026-04-28T17:25:04.277905Z","end":"2026-04-28T17:25:04.436392Z","steps":["trace[191426635] 'process raft request' (duration: 158.198877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:11.408358Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213412476364856829,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T17:25:11.836803Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.187116825s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T17:25:11.838031Z","caller":"traceutil/trace.go:171","msg":"trace[821820734] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.189877468s","start":"2026-04-28T17:25:10.648129Z","end":"2026-04-28T17:25:11.838006Z","steps":["trace[821820734] 'process raft request' (duration: 1.18919078s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:11.838235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:10.648102Z","time spent":"1.18999853s","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T17:25:11.861568Z","caller":"traceutil/trace.go:171","msg":"trace[1015046972] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:619; }","duration":"954.266695ms","start":"2026-04-28T17:25:10.907283Z","end":"2026-04-28T17:25:11.86155Z","steps":["trace[1015046972] 'read index received' (duration: 929.724068ms)","trace[1015046972] 'applied index is now lower than readState.Index' (duration: 24.541937ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:25:11.861725Z","caller":"traceutil/trace.go:171","msg":"trace[1657036466] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"986.871676ms","start":"2026-04-28T17:25:10.874808Z","end":"2026-04-28T17:25:11.861679Z","steps":["trace[1657036466] 'process raft request' (duration: 986.64771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:11.861816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.02644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T17:25:11.861851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.571293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r8lc9\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-28T17:25:11.8619Z","caller":"traceutil/trace.go:171","msg":"trace[197104594] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r8lc9; range_end:; response_count:1; response_revision:601; }","duration":"954.667205ms","start":"2026-04-28T17:25:10.907221Z","end":"2026-04-28T17:25:11.861888Z","steps":["trace[197104594] 'agreement among raft nodes before linearized reading' (duration: 954.527492ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:11.861899Z","caller":"traceutil/trace.go:171","msg":"trace[1084309806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"871.146333ms","start":"2026-04-28T17:25:10.990738Z","end":"2026-04-28T17:25:11.861885Z","steps":["trace[1084309806] 'agreement among raft nodes before linearized reading' (duration: 870.983199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:11.861929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:10.907199Z","time spent":"954.724516ms","remote":"127.0.0.1:41562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-r8lc9\" "} {"level":"warn","ts":"2026-04-28T17:25:11.861952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:10.990723Z","time spent":"871.223346ms","remote":"127.0.0.1:41374","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-28T17:25:11.86184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:10.874785Z","time spent":"986.979059ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T17:25:13.01658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.696951ms","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-28T17:25:13.016686Z","caller":"traceutil/trace.go:171","msg":"trace[1238902762] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:601; }","duration":"448.815574ms","start":"2026-04-28T17:25:12.567854Z","end":"2026-04-28T17:25:13.01667Z","steps":["trace[1238902762] 'range keys from in-memory index tree' (duration: 448.682431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:13.016941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090809359s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:25:13.017076Z","caller":"traceutil/trace.go:171","msg":"trace[580615135] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:621; }","duration":"1.026153969s","start":"2026-04-28T17:25:11.990912Z","end":"2026-04-28T17:25:13.017066Z","steps":["trace[580615135] 'read index received' (duration: 397.867954ms)","trace[580615135] 'applied index is now lower than readState.Index' (duration: 628.285185ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:25:13.017348Z","caller":"traceutil/trace.go:171","msg":"trace[202971362] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.124792957s","start":"2026-04-28T17:25:11.892536Z","end":"2026-04-28T17:25:13.017329Z","steps":["trace[202971362] 'process raft request' (duration: 1.124468039s)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:13.01743Z","caller":"traceutil/trace.go:171","msg":"trace[1408125897] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.143526514s","start":"2026-04-28T17:25:11.873868Z","end":"2026-04-28T17:25:13.017395Z","steps":["trace[1408125897] 'process raft request' (duration: 52.201682ms)","trace[1408125897] 'compare' (duration: 1.090443299s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:25:13.017475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:11.892514Z","time spent":"1.124901289s","remote":"127.0.0.1:41618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T17:25:13.017527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.929604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T17:25:13.017571Z","caller":"traceutil/trace.go:171","msg":"trace[1014108589] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:603; }","duration":"961.008126ms","start":"2026-04-28T17:25:12.056547Z","end":"2026-04-28T17:25:13.017556Z","steps":["trace[1014108589] 'agreement among raft nodes before linearized reading' (duration: 960.877462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:13.017611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:11.873851Z","time spent":"1.143655328s","remote":"127.0.0.1:41562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T17:25:13.01764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:12.05652Z","time spent":"961.104278ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T17:25:13.017753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.921569ms","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-28T17:25:13.017855Z","caller":"traceutil/trace.go:171","msg":"trace[1050318419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"942.060892ms","start":"2026-04-28T17:25:12.075768Z","end":"2026-04-28T17:25:13.017829Z","steps":["trace[1050318419] 'agreement among raft nodes before linearized reading' (duration: 941.796856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:13.017871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0269683s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T17:25:13.017918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:12.075742Z","time spent":"942.162495ms","remote":"127.0.0.1:41660","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-28T17:25:13.017926Z","caller":"traceutil/trace.go:171","msg":"trace[129176302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"1.027052753s","start":"2026-04-28T17:25:11.990858Z","end":"2026-04-28T17:25:13.017911Z","steps":["trace[129176302] 'agreement among raft nodes before linearized reading' (duration: 1.02695006s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:13.017931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.762975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-28T17:25:13.017941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.934082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-28T17:25:13.01798Z","caller":"traceutil/trace.go:171","msg":"trace[385807426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"957.030975ms","start":"2026-04-28T17:25:12.060938Z","end":"2026-04-28T17:25:13.017969Z","steps":["trace[385807426] 'agreement among raft nodes before linearized reading' (duration: 956.874221ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:13.017979Z","caller":"traceutil/trace.go:171","msg":"trace[13668697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"950.863918ms","start":"2026-04-28T17:25:12.067101Z","end":"2026-04-28T17:25:13.017965Z","steps":["trace[13668697] 'agreement among raft nodes before linearized reading' (duration: 950.721624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:13.017977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:11.990842Z","time spent":"1.027122174s","remote":"127.0.0.1:41374","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-28T17:25:13.01801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:12.060924Z","time spent":"957.080076ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T17:25:13.018019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:12.06709Z","time spent":"950.918729ms","remote":"127.0.0.1:41660","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-28T17:25:13.190666Z","caller":"traceutil/trace.go:171","msg":"trace[1456535826] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"167.166806ms","start":"2026-04-28T17:25:13.02348Z","end":"2026-04-28T17:25:13.190647Z","steps":["trace[1456535826] 'process raft request' (duration: 166.799456ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:13.190929Z","caller":"traceutil/trace.go:171","msg":"trace[2048347741] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"167.29175ms","start":"2026-04-28T17:25:13.023581Z","end":"2026-04-28T17:25:13.190872Z","steps":["trace[2048347741] 'process raft request' (duration: 166.844398ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:13.191016Z","caller":"traceutil/trace.go:171","msg":"trace[635470180] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"164.56268ms","start":"2026-04-28T17:25:13.026443Z","end":"2026-04-28T17:25:13.191006Z","steps":["trace[635470180] 'process raft request' (duration: 164.008425ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:13.212799Z","caller":"traceutil/trace.go:171","msg":"trace[57505968] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:623; }","duration":"185.229457ms","start":"2026-04-28T17:25:13.027539Z","end":"2026-04-28T17:25:13.212768Z","steps":["trace[57505968] 'read index received' (duration: 27.009139ms)","trace[57505968] 'applied index is now lower than readState.Index' (duration: 158.218458ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:25:13.213004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.441083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-28T17:25:13.213039Z","caller":"traceutil/trace.go:171","msg":"trace[1187229514] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:607; }","duration":"185.509075ms","start":"2026-04-28T17:25:13.027518Z","end":"2026-04-28T17:25:13.213027Z","steps":["trace[1187229514] 'agreement among raft nodes before linearized reading' (duration: 185.413683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:13.213026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.18002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:25:13.213088Z","caller":"traceutil/trace.go:171","msg":"trace[54297296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"184.257842ms","start":"2026-04-28T17:25:13.028815Z","end":"2026-04-28T17:25:13.213073Z","steps":["trace[54297296] 'agreement among raft nodes before linearized reading' (duration: 184.15418ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:15.380316Z","caller":"traceutil/trace.go:171","msg":"trace[652314836] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"182.677542ms","start":"2026-04-28T17:25:15.197616Z","end":"2026-04-28T17:25:15.380294Z","steps":["trace[652314836] 'process raft request' (duration: 182.552649ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:15.383089Z","caller":"traceutil/trace.go:171","msg":"trace[1504615951] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"184.262963ms","start":"2026-04-28T17:25:15.198807Z","end":"2026-04-28T17:25:15.38307Z","steps":["trace[1504615951] 'process raft request' (duration: 184.14971ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:15.383202Z","caller":"traceutil/trace.go:171","msg":"trace[1742391552] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"184.047867ms","start":"2026-04-28T17:25:15.199093Z","end":"2026-04-28T17:25:15.383141Z","steps":["trace[1742391552] 'process raft request' (duration: 183.919654ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:15.383328Z","caller":"traceutil/trace.go:171","msg":"trace[907432073] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"177.549901ms","start":"2026-04-28T17:25:15.205741Z","end":"2026-04-28T17:25:15.38329Z","steps":["trace[907432073] 'process raft request' (duration: 177.303934ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:29.191134Z","caller":"traceutil/trace.go:171","msg":"trace[2029305196] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"199.056717ms","start":"2026-04-28T17:25:28.992048Z","end":"2026-04-28T17:25:29.191105Z","steps":["trace[2029305196] 'read index received' (duration: 198.914244ms)","trace[2029305196] 'applied index is now lower than readState.Index' (duration: 141.053µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:25:29.191178Z","caller":"traceutil/trace.go:171","msg":"trace[193664663] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"368.891748ms","start":"2026-04-28T17:25:28.822254Z","end":"2026-04-28T17:25:29.191146Z","steps":["trace[193664663] 'process raft request' (duration: 368.658892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:29.191275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.215012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:25:29.19132Z","caller":"traceutil/trace.go:171","msg":"trace[114343516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"199.306023ms","start":"2026-04-28T17:25:28.992Z","end":"2026-04-28T17:25:29.191306Z","steps":["trace[114343516] 'agreement among raft nodes before linearized reading' (duration: 199.222381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:29.19135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:28.822238Z","time spent":"369.028241ms","remote":"127.0.0.1:41660","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-28T17:25:29.693495Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213412476364857141,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T17:25:29.861021Z","caller":"traceutil/trace.go:171","msg":"trace[588012904] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"668.049678ms","start":"2026-04-28T17:25:29.192948Z","end":"2026-04-28T17:25:29.860998Z","steps":["trace[588012904] 'read index received' (duration: 655.373835ms)","trace[588012904] 'applied index is now lower than readState.Index' (duration: 12.674843ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:25:29.86162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.654244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:25:29.861675Z","caller":"traceutil/trace.go:171","msg":"trace[1839231099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"668.720376ms","start":"2026-04-28T17:25:29.192942Z","end":"2026-04-28T17:25:29.861662Z","steps":["trace[1839231099] 'agreement among raft nodes before linearized reading' (duration: 668.627054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:29.861716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:29.192884Z","time spent":"668.820138ms","remote":"127.0.0.1:41378","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-28T17:25:29.861714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.181818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-28T17:25:29.861729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.26522ms","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-28T17:25:29.861786Z","caller":"traceutil/trace.go:171","msg":"trace[1584999672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:762; }","duration":"629.26273ms","start":"2026-04-28T17:25:29.232507Z","end":"2026-04-28T17:25:29.86177Z","steps":["trace[1584999672] 'agreement among raft nodes before linearized reading' (duration: 629.037894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:29.861787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.114564ms","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-28T17:25:29.861835Z","caller":"traceutil/trace.go:171","msg":"trace[2020625446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:762; }","duration":"396.197035ms","start":"2026-04-28T17:25:29.465627Z","end":"2026-04-28T17:25:29.861824Z","steps":["trace[2020625446] 'agreement among raft nodes before linearized reading' (duration: 396.040341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:29.861718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.332896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T17:25:29.861866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:29.465606Z","time spent":"396.253417ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T17:25:29.86185Z","caller":"traceutil/trace.go:171","msg":"trace[1230364093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:762; }","duration":"344.374603ms","start":"2026-04-28T17:25:29.51741Z","end":"2026-04-28T17:25:29.861785Z","steps":["trace[1230364093] 'agreement among raft nodes before linearized reading' (duration: 344.152997ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:29.861891Z","caller":"traceutil/trace.go:171","msg":"trace[1539359389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:762; }","duration":"379.54201ms","start":"2026-04-28T17:25:29.482327Z","end":"2026-04-28T17:25:29.861869Z","steps":["trace[1539359389] 'agreement among raft nodes before linearized reading' (duration: 379.269613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:29.861913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:29.517381Z","time spent":"344.521837ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T17:25:29.861935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:29.482309Z","time spent":"379.615973ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T17:25:29.861827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:29.23246Z","time spent":"629.358562ms","remote":"127.0.0.1:41660","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-28T17:25:29.861285Z","caller":"traceutil/trace.go:171","msg":"trace[1037789316] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"839.08823ms","start":"2026-04-28T17:25:29.022183Z","end":"2026-04-28T17:25:29.861271Z","steps":["trace[1037789316] 'process raft request' (duration: 826.113ms)","trace[1037789316] 'compare' (duration: 12.19496ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:25:29.862325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:29.022143Z","time spent":"840.113447ms","remote":"127.0.0.1:41618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T17:25:36.10563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.518073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:25:36.105835Z","caller":"traceutil/trace.go:171","msg":"trace[194410289] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"183.330776ms","start":"2026-04-28T17:25:35.922484Z","end":"2026-04-28T17:25:36.105815Z","steps":["trace[194410289] 'process raft request' (duration: 22.519724ms)","trace[194410289] 'compare' (duration: 160.326789ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:25:36.105746Z","caller":"traceutil/trace.go:171","msg":"trace[1194768331] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:861; }","duration":"114.289004ms","start":"2026-04-28T17:25:35.991439Z","end":"2026-04-28T17:25:36.105728Z","steps":["trace[1194768331] 'read index received' (duration: 45.231µs)","trace[1194768331] 'applied index is now lower than readState.Index' (duration: 114.242583ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:25:36.105969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.53258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:25:36.106001Z","caller":"traceutil/trace.go:171","msg":"trace[767458776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:835; }","duration":"114.603212ms","start":"2026-04-28T17:25:35.991385Z","end":"2026-04-28T17:25:36.105988Z","steps":["trace[767458776] 'agreement among raft nodes before linearized reading' (duration: 114.542031ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:40.285293Z","caller":"traceutil/trace.go:171","msg":"trace[1768942356] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"130.929949ms","start":"2026-04-28T17:25:40.154335Z","end":"2026-04-28T17:25:40.285265Z","steps":["trace[1768942356] 'process raft request' (duration: 130.782725ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:25:59.710302Z","caller":"traceutil/trace.go:171","msg":"trace[1010993712] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"356.917163ms","start":"2026-04-28T17:25:59.353364Z","end":"2026-04-28T17:25:59.710281Z","steps":["trace[1010993712] 'read index received' (duration: 356.758168ms)","trace[1010993712] 'applied index is now lower than readState.Index' (duration: 157.835µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:25:59.710416Z","caller":"traceutil/trace.go:171","msg":"trace[1325595865] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"606.853928ms","start":"2026-04-28T17:25:59.103532Z","end":"2026-04-28T17:25:59.710386Z","steps":["trace[1325595865] 'process raft request' (duration: 606.56317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:59.710471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.100188ms","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-28T17:25:59.7105Z","caller":"traceutil/trace.go:171","msg":"trace[1521104742] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:965; }","duration":"357.184561ms","start":"2026-04-28T17:25:59.353309Z","end":"2026-04-28T17:25:59.710493Z","steps":["trace[1521104742] 'agreement among raft nodes before linearized reading' (duration: 357.050107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:25:59.71053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:59.353288Z","time spent":"357.236581ms","remote":"127.0.0.1:41660","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-28T17:25:59.710583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:25:59.10351Z","time spent":"606.993242ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T17:26:02.164045Z","caller":"traceutil/trace.go:171","msg":"trace[2091421556] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"113.438753ms","start":"2026-04-28T17:26:02.050542Z","end":"2026-04-28T17:26:02.163981Z","steps":["trace[2091421556] 'process raft request' (duration: 113.229707ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:26:26.073653Z","caller":"traceutil/trace.go:171","msg":"trace[970301794] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1090; }","duration":"245.227494ms","start":"2026-04-28T17:26:25.828399Z","end":"2026-04-28T17:26:26.073626Z","steps":["trace[970301794] 'read index received' (duration: 245.05456ms)","trace[970301794] 'applied index is now lower than readState.Index' (duration: 171.594µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:26:26.073758Z","caller":"traceutil/trace.go:171","msg":"trace[894408537] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"252.806117ms","start":"2026-04-28T17:26:25.820927Z","end":"2026-04-28T17:26:26.073733Z","steps":["trace[894408537] 'process raft request' (duration: 252.54354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:26:26.073857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.662963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T17:26:26.073912Z","caller":"traceutil/trace.go:171","msg":"trace[2043850832] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1054; }","duration":"230.762315ms","start":"2026-04-28T17:26:25.843133Z","end":"2026-04-28T17:26:26.073895Z","steps":["trace[2043850832] 'agreement among raft nodes before linearized reading' (duration: 230.657642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:26:26.073974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.551972ms","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-28T17:26:26.074109Z","caller":"traceutil/trace.go:171","msg":"trace[762253000] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1054; }","duration":"245.686476ms","start":"2026-04-28T17:26:25.828367Z","end":"2026-04-28T17:26:26.074054Z","steps":["trace[762253000] 'agreement among raft nodes before linearized reading' (duration: 245.401978ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:26:30.723785Z","caller":"traceutil/trace.go:171","msg":"trace[351125858] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"137.411012ms","start":"2026-04-28T17:26:30.586342Z","end":"2026-04-28T17:26:30.723753Z","steps":["trace[351125858] 'process raft request' (duration: 97.781543ms)","trace[351125858] 'compare' (duration: 39.516426ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:26:34.420392Z","caller":"traceutil/trace.go:171","msg":"trace[108433993] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1119; }","duration":"247.050128ms","start":"2026-04-28T17:26:34.17332Z","end":"2026-04-28T17:26:34.420371Z","steps":["trace[108433993] 'read index received' (duration: 246.890123ms)","trace[108433993] 'applied index is now lower than readState.Index' (duration: 158.965µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:26:34.420475Z","caller":"traceutil/trace.go:171","msg":"trace[2122946894] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"319.664113ms","start":"2026-04-28T17:26:34.100786Z","end":"2026-04-28T17:26:34.42045Z","steps":["trace[2122946894] 'process raft request' (duration: 319.449886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:26:34.420679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.336766ms","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-28T17:26:34.420784Z","caller":"traceutil/trace.go:171","msg":"trace[1621694341] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1081; }","duration":"247.456479ms","start":"2026-04-28T17:26:34.173307Z","end":"2026-04-28T17:26:34.420763Z","steps":["trace[1621694341] 'agreement among raft nodes before linearized reading' (duration: 247.183391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:26:34.420808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:26:34.100762Z","time spent":"319.758626ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T17:26:34.780927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.51821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:26:34.781156Z","caller":"traceutil/trace.go:171","msg":"trace[1712651151] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1120; }","duration":"181.065175ms","start":"2026-04-28T17:26:34.600068Z","end":"2026-04-28T17:26:34.781134Z","steps":["trace[1712651151] 'read index received' (duration: 174.540326ms)","trace[1712651151] 'applied index is now lower than readState.Index' (duration: 6.523459ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:26:34.781181Z","caller":"traceutil/trace.go:171","msg":"trace[1620995073] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"356.848869ms","start":"2026-04-28T17:26:34.424311Z","end":"2026-04-28T17:26:34.78116Z","steps":["trace[1620995073] 'process raft request' (duration: 134.003769ms)","trace[1620995073] 'compare' (duration: 221.937032ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:26:34.781315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:26:34.424288Z","time spent":"356.937561ms","remote":"127.0.0.1:41660","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-28T17:26:34.781322Z","caller":"traceutil/trace.go:171","msg":"trace[1591232854] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"356.429637ms","start":"2026-04-28T17:26:34.424877Z","end":"2026-04-28T17:26:34.781307Z","steps":["trace[1591232854] 'process raft request' (duration: 356.173089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:26:34.781576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.509408ms","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-28T17:26:34.781634Z","caller":"traceutil/trace.go:171","msg":"trace[966371657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1083; }","duration":"181.602841ms","start":"2026-04-28T17:26:34.600018Z","end":"2026-04-28T17:26:34.781621Z","steps":["trace[966371657] 'agreement among raft nodes before linearized reading' (duration: 181.329923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:26:34.782227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:26:34.424867Z","time spent":"356.55959ms","remote":"127.0.0.1:41660","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-28T17:26:35.043051Z","caller":"traceutil/trace.go:171","msg":"trace[629561940] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"191.160052ms","start":"2026-04-28T17:26:34.851866Z","end":"2026-04-28T17:26:35.043025Z","steps":["trace[629561940] 'process raft request' (duration: 190.881074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:26:48.490519Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213412476364858112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T17:26:48.534318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:26:47.931256Z","time spent":"603.05813ms","remote":"127.0.0.1:41400","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-28T17:26:48.534316Z","caller":"traceutil/trace.go:171","msg":"trace[965898709] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1219; }","duration":"544.007058ms","start":"2026-04-28T17:26:47.990285Z","end":"2026-04-28T17:26:48.534292Z","steps":["trace[965898709] 'read index received' (duration: 543.799022ms)","trace[965898709] 'applied index is now lower than readState.Index' (duration: 206.826µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:26:48.534469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.62872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:26:48.534508Z","caller":"traceutil/trace.go:171","msg":"trace[1459078957] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1176; }","duration":"393.723462ms","start":"2026-04-28T17:26:48.140769Z","end":"2026-04-28T17:26:48.534493Z","steps":["trace[1459078957] 'agreement among raft nodes before linearized reading' (duration: 393.64601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:26:48.534479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.180514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T17:26:48.534541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:26:48.140746Z","time spent":"393.785564ms","remote":"127.0.0.1:40858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-04-28T17:26:48.53456Z","caller":"traceutil/trace.go:171","msg":"trace[607376782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1176; }","duration":"544.308908ms","start":"2026-04-28T17:26:47.990231Z","end":"2026-04-28T17:26:48.53454Z","steps":["trace[607376782] 'agreement among raft nodes before linearized reading' (duration: 544.173304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:26:48.534602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:26:47.990206Z","time spent":"544.38889ms","remote":"127.0.0.1:41374","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-28T17:26:49.687161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213412476364858132,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T17:26:50.0324Z","caller":"traceutil/trace.go:171","msg":"trace[935493306] linearizableReadLoop","detail":"{readStateIndex:1228; appliedIndex:1227; }","duration":"845.498468ms","start":"2026-04-28T17:26:49.186885Z","end":"2026-04-28T17:26:50.032383Z","steps":["trace[935493306] 'read index received' (duration: 845.421856ms)","trace[935493306] 'applied index is now lower than readState.Index' (duration: 75.752µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:26:50.032482Z","caller":"traceutil/trace.go:171","msg":"trace[1801282981] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"889.061247ms","start":"2026-04-28T17:26:49.143383Z","end":"2026-04-28T17:26:50.032444Z","steps":["trace[1801282981] 'process raft request' (duration: 888.896812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:26:50.032553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.00572ms","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-28T17:26:50.03257Z","caller":"traceutil/trace.go:171","msg":"trace[1509790032] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1184; }","duration":"586.056121ms","start":"2026-04-28T17:26:49.446508Z","end":"2026-04-28T17:26:50.032564Z","steps":["trace[1509790032] 'agreement among raft nodes before linearized reading' (duration: 586.031501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:26:50.032597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:26:49.446488Z","time spent":"586.103912ms","remote":"127.0.0.1:41528","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-28T17:26:50.032639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:26:49.143362Z","time spent":"889.196751ms","remote":"127.0.0.1:41660","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-28T17:26:50.032521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.617511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:26:50.032869Z","caller":"traceutil/trace.go:171","msg":"trace[1775717778] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1184; }","duration":"846.022043ms","start":"2026-04-28T17:26:49.186842Z","end":"2026-04-28T17:26:50.032864Z","steps":["trace[1775717778] 'agreement among raft nodes before linearized reading' (duration: 845.633752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:26:50.032889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:26:49.18682Z","time spent":"846.063495ms","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-04-28T17:27:02.802844Z","caller":"traceutil/trace.go:171","msg":"trace[2106146142] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"106.706516ms","start":"2026-04-28T17:27:02.696122Z","end":"2026-04-28T17:27:02.802828Z","steps":["trace[2106146142] 'process raft request' (duration: 106.612283ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:11.756878Z","caller":"traceutil/trace.go:171","msg":"trace[2042387583] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"395.529035ms","start":"2026-04-28T17:27:11.361317Z","end":"2026-04-28T17:27:11.756846Z","steps":["trace[2042387583] 'process raft request' (duration: 372.036578ms)","trace[2042387583] 'compare' (duration: 22.83757ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:27:11.757136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:27:11.361298Z","time spent":"395.682069ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T17:27:12.093443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.454065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T17:27:12.093565Z","caller":"traceutil/trace.go:171","msg":"trace[214419709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1296; }","duration":"100.68203ms","start":"2026-04-28T17:27:11.992859Z","end":"2026-04-28T17:27:12.093541Z","steps":["trace[214419709] 'range keys from in-memory index tree' (duration: 100.356472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:27:12.093901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.667487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-28T17:27:12.09399Z","caller":"traceutil/trace.go:171","msg":"trace[2130478522] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1296; }","duration":"148.788961ms","start":"2026-04-28T17:27:11.945179Z","end":"2026-04-28T17:27:12.093967Z","steps":["trace[2130478522] 'range keys from in-memory index tree' (duration: 148.481623ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:13.170446Z","caller":"traceutil/trace.go:171","msg":"trace[2033222417] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"244.092209ms","start":"2026-04-28T17:27:12.926322Z","end":"2026-04-28T17:27:13.170414Z","steps":["trace[2033222417] 'process raft request' (duration: 243.945125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:27:13.45759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.442136ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T17:27:13.457696Z","caller":"traceutil/trace.go:171","msg":"trace[2082685737] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1348; }","duration":"206.706152ms","start":"2026-04-28T17:27:13.250964Z","end":"2026-04-28T17:27:13.45767Z","steps":["trace[2082685737] 'read index received' (duration: 41.251µs)","trace[2082685737] 'applied index is now lower than readState.Index' (duration: 206.663611ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:27:13.457854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.410359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-28T17:27:13.457858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.891306ms","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-28T17:27:13.457879Z","caller":"traceutil/trace.go:171","msg":"trace[1547708446] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1300; }","duration":"163.48085ms","start":"2026-04-28T17:27:13.294391Z","end":"2026-04-28T17:27:13.457871Z","steps":["trace[1547708446] 'agreement among raft nodes before linearized reading' (duration: 163.377808ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:13.457973Z","caller":"traceutil/trace.go:171","msg":"trace[668738164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1300; }","duration":"207.04353ms","start":"2026-04-28T17:27:13.250914Z","end":"2026-04-28T17:27:13.457958Z","steps":["trace[668738164] 'agreement among raft nodes before linearized reading' (duration: 206.825534ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:13.708012Z","caller":"traceutil/trace.go:171","msg":"trace[1788833561] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"244.96265ms","start":"2026-04-28T17:27:13.463018Z","end":"2026-04-28T17:27:13.70798Z","steps":["trace[1788833561] 'process raft request' (duration: 204.99973ms)","trace[1788833561] 'compare' (duration: 39.57581ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:27:13.708154Z","caller":"traceutil/trace.go:171","msg":"trace[47676984] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"244.972309ms","start":"2026-04-28T17:27:13.463161Z","end":"2026-04-28T17:27:13.708134Z","steps":["trace[47676984] 'process raft request' (duration: 244.689313ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:13.708279Z","caller":"traceutil/trace.go:171","msg":"trace[559552392] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1349; }","duration":"238.995783ms","start":"2026-04-28T17:27:13.469262Z","end":"2026-04-28T17:27:13.708257Z","steps":["trace[559552392] 'read index received' (duration: 49.433963ms)","trace[559552392] 'applied index is now lower than readState.Index' (duration: 189.56085ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:27:13.708406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.135306ms","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-28T17:27:13.708436Z","caller":"traceutil/trace.go:171","msg":"trace[463244136] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1304; }","duration":"239.192058ms","start":"2026-04-28T17:27:13.469233Z","end":"2026-04-28T17:27:13.708426Z","steps":["trace[463244136] 'agreement among raft nodes before linearized reading' (duration: 239.067235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:27:19.974819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.659839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T17:27:19.974917Z","caller":"traceutil/trace.go:171","msg":"trace[1597125799] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1395; }","duration":"551.494551ms","start":"2026-04-28T17:27:19.423407Z","end":"2026-04-28T17:27:19.974901Z","steps":["trace[1597125799] 'read index received' (duration: 45.031µs)","trace[1597125799] 'applied index is now lower than readState.Index' (duration: 551.44822ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T17:27:19.974994Z","caller":"traceutil/trace.go:171","msg":"trace[557203028] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"672.4997ms","start":"2026-04-28T17:27:19.302483Z","end":"2026-04-28T17:27:19.974983Z","steps":["trace[557203028] 'process raft request' (duration: 115.545794ms)","trace[557203028] 'compare' (duration: 556.546576ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:27:19.975049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:27:19.302466Z","time spent":"672.551262ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T17:27:19.975332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.894675ms","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-28T17:27:19.975329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.121596ms","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-28T17:27:19.975363Z","caller":"traceutil/trace.go:171","msg":"trace[1376745656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1346; }","duration":"241.962267ms","start":"2026-04-28T17:27:19.733391Z","end":"2026-04-28T17:27:19.975353Z","steps":["trace[1376745656] 'agreement among raft nodes before linearized reading' (duration: 241.864744ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:19.975376Z","caller":"traceutil/trace.go:171","msg":"trace[645636318] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1346; }","duration":"435.208888ms","start":"2026-04-28T17:27:19.540153Z","end":"2026-04-28T17:27:19.975362Z","steps":["trace[645636318] 'agreement among raft nodes before linearized reading' (duration: 435.062344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:27:19.975408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:27:19.540132Z","time spent":"435.269049ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T17:27:19.975453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.041275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T17:27:19.975453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.560307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-28T17:27:19.975502Z","caller":"traceutil/trace.go:171","msg":"trace[1662755676] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1346; }","duration":"552.136937ms","start":"2026-04-28T17:27:19.423351Z","end":"2026-04-28T17:27:19.975487Z","steps":["trace[1662755676] 'agreement among raft nodes before linearized reading' (duration: 552.041045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:27:19.97549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.654662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T17:27:19.975537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:27:19.42333Z","time spent":"552.198168ms","remote":"127.0.0.1:41774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-04-28T17:27:19.975532Z","caller":"traceutil/trace.go:171","msg":"trace[915556125] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1346; }","duration":"274.669429ms","start":"2026-04-28T17:27:19.70084Z","end":"2026-04-28T17:27:19.97551Z","steps":["trace[915556125] 'agreement among raft nodes before linearized reading' (duration: 274.452433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:27:19.975568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.356565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T17:27:19.975566Z","caller":"traceutil/trace.go:171","msg":"trace[215900225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1346; }","duration":"363.768824ms","start":"2026-04-28T17:27:19.611775Z","end":"2026-04-28T17:27:19.975543Z","steps":["trace[215900225] 'agreement among raft nodes before linearized reading' (duration: 363.648031ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:19.975597Z","caller":"traceutil/trace.go:171","msg":"trace[141676958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1346; }","duration":"171.423226ms","start":"2026-04-28T17:27:19.804165Z","end":"2026-04-28T17:27:19.975588Z","steps":["trace[141676958] 'agreement among raft nodes before linearized reading' (duration: 171.342044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T17:27:19.975606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T17:27:19.611753Z","time spent":"363.846126ms","remote":"127.0.0.1:41374","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-28T17:27:19.975618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.547472ms","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-28T17:27:19.975649Z","caller":"traceutil/trace.go:171","msg":"trace[714766008] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1346; }","duration":"242.612073ms","start":"2026-04-28T17:27:19.733029Z","end":"2026-04-28T17:27:19.975641Z","steps":["trace[714766008] 'agreement among raft nodes before linearized reading' (duration: 242.52243ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:20.252591Z","caller":"traceutil/trace.go:171","msg":"trace[171939568] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"271.642594ms","start":"2026-04-28T17:27:19.980928Z","end":"2026-04-28T17:27:20.25257Z","steps":["trace[171939568] 'process raft request' (duration: 271.527852ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:20.252551Z","caller":"traceutil/trace.go:171","msg":"trace[1500217647] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"271.940672ms","start":"2026-04-28T17:27:19.980582Z","end":"2026-04-28T17:27:20.252522Z","steps":["trace[1500217647] 'process raft request' (duration: 268.964389ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:20.252687Z","caller":"traceutil/trace.go:171","msg":"trace[1161940608] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"271.688826ms","start":"2026-04-28T17:27:19.980979Z","end":"2026-04-28T17:27:20.252668Z","steps":["trace[1161940608] 'process raft request' (duration: 271.524772ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:20.252741Z","caller":"traceutil/trace.go:171","msg":"trace[1585037406] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"271.830449ms","start":"2026-04-28T17:27:19.980896Z","end":"2026-04-28T17:27:20.252726Z","steps":["trace[1585037406] 'process raft request' (duration: 271.485361ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:20.252738Z","caller":"traceutil/trace.go:171","msg":"trace[132768517] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1396; }","duration":"262.656444ms","start":"2026-04-28T17:27:19.989891Z","end":"2026-04-28T17:27:20.252547Z","steps":["trace[132768517] 'read index received' (duration: 6.167731ms)","trace[132768517] 'applied index is now lower than readState.Index' (duration: 256.487703ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:27:20.252881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.980381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T17:27:20.252894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.033595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-28T17:27:20.252922Z","caller":"traceutil/trace.go:171","msg":"trace[1153187423] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1351; }","duration":"193.090497ms","start":"2026-04-28T17:27:20.059825Z","end":"2026-04-28T17:27:20.252915Z","steps":["trace[1153187423] 'agreement among raft nodes before linearized reading' (duration: 193.017695ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:20.252928Z","caller":"traceutil/trace.go:171","msg":"trace[2054518930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1351; }","duration":"263.066034ms","start":"2026-04-28T17:27:19.989844Z","end":"2026-04-28T17:27:20.25291Z","steps":["trace[2054518930] 'agreement among raft nodes before linearized reading' (duration: 262.985002ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:26.393462Z","caller":"traceutil/trace.go:171","msg":"trace[436382009] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"113.961956ms","start":"2026-04-28T17:27:26.279484Z","end":"2026-04-28T17:27:26.393446Z","steps":["trace[436382009] 'process raft request' (duration: 105.92999ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:26.393652Z","caller":"traceutil/trace.go:171","msg":"trace[1291974455] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"113.473495ms","start":"2026-04-28T17:27:26.280147Z","end":"2026-04-28T17:27:26.393621Z","steps":["trace[1291974455] 'process raft request' (duration: 113.219908ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:35.48444Z","caller":"traceutil/trace.go:171","msg":"trace[440102924] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"189.66684ms","start":"2026-04-28T17:27:35.294756Z","end":"2026-04-28T17:27:35.484423Z","steps":["trace[440102924] 'process raft request' (duration: 189.563617ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:35.515225Z","caller":"traceutil/trace.go:171","msg":"trace[1731702223] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"150.975999ms","start":"2026-04-28T17:27:35.364221Z","end":"2026-04-28T17:27:35.515197Z","steps":["trace[1731702223] 'process raft request' (duration: 150.814595ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:40.540145Z","caller":"traceutil/trace.go:171","msg":"trace[231289514] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"133.5233ms","start":"2026-04-28T17:27:40.406588Z","end":"2026-04-28T17:27:40.540112Z","steps":["trace[231289514] 'process raft request' (duration: 133.323925ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:27:44.673952Z","caller":"traceutil/trace.go:171","msg":"trace[1634723067] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"111.340105ms","start":"2026-04-28T17:27:44.56258Z","end":"2026-04-28T17:27:44.67392Z","steps":["trace[1634723067] 'process raft request' (duration: 111.178821ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:29:01.337741Z","caller":"traceutil/trace.go:171","msg":"trace[1484491863] linearizableReadLoop","detail":"{readStateIndex:1880; appliedIndex:1879; }","duration":"105.545928ms","start":"2026-04-28T17:29:01.232169Z","end":"2026-04-28T17:29:01.337715Z","steps":["trace[1484491863] 'read index received' (duration: 103.99318ms)","trace[1484491863] 'applied index is now lower than readState.Index' (duration: 1.551408ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T17:29:01.33803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.107894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T17:29:01.338005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.800485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-28T17:29:01.338077Z","caller":"traceutil/trace.go:171","msg":"trace[1590217791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1810; }","duration":"100.200816ms","start":"2026-04-28T17:29:01.237863Z","end":"2026-04-28T17:29:01.338064Z","steps":["trace[1590217791] 'agreement among raft nodes before linearized reading' (duration: 100.008882ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:29:01.338089Z","caller":"traceutil/trace.go:171","msg":"trace[599970388] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1810; }","duration":"105.963919ms","start":"2026-04-28T17:29:01.232112Z","end":"2026-04-28T17:29:01.338076Z","steps":["trace[599970388] 'agreement among raft nodes before linearized reading' (duration: 105.710632ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T17:29:01.338271Z","caller":"traceutil/trace.go:171","msg":"trace[1309686669] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"136.38798ms","start":"2026-04-28T17:29:01.201382Z","end":"2026-04-28T17:29:01.337769Z","steps":["trace[1309686669] 'process raft request' (duration: 134.881224ms)"],"step_count":1}