{"level":"warn","ts":"2026-04-25T14:17:37.078548Z","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-25T14:17:37.078661Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-25T14:17:37.07877Z","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-25T14:17:37.078787Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-04-25T14:17:37.078814Z","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-25T14:17:37.079811Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-04-25T14:17:37.079916Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-25T14:17:37.08499Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.823821ms"} {"level":"info","ts":"2026-04-25T14:17:37.095471Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-04-25T14:17:37.095538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-04-25T14:17:37.095563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-04-25T14:17:37.095576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-25T14:17:37.095585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-04-25T14:17:37.09562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-04-25T14:17:37.103899Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-25T14:17:37.105421Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-25T14:17:37.10739Z","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-25T14:17:37.109602Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-25T14:17:37.10975Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-25T14:17:37.109851Z","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-25T14:17:37.11202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-04-25T14:17:37.111518Z","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-25T14:17:37.112286Z","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-25T14:17:37.112394Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-04-25T14:17:37.114308Z","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-25T14:17:37.114462Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-04-25T14:17:37.114512Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-04-25T14:17:37.114526Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-25T14:17:37.114566Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-25T14:17:37.196817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-04-25T14:17:37.19689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-25T14:17:37.196919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-04-25T14:17:37.196938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-04-25T14:17:37.196955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-04-25T14:17:37.19697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-04-25T14:17:37.196983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-04-25T14:17:37.197691Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-25T14:17:37.198822Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-25T14:17:37.198897Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-25T14:17:37.198823Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-04-25T14:17:37.200591Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-25T14:17:37.200612Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-25T14:17:37.201949Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-04-25T14:17:37.202471Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-25T14:17:37.207707Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-04-25T14:17:37.207857Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-25T14:17:37.207945Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-25T14:17:38.76603Z","caller":"traceutil/trace.go:171","msg":"trace[1431244343] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"112.589474ms","start":"2026-04-25T14:17:38.653417Z","end":"2026-04-25T14:17:38.766006Z","steps":["trace[1431244343] 'read index received' (duration: 112.358073ms)","trace[1431244343] 'applied index is now lower than readState.Index' (duration: 230.34µs)"],"step_count":2} {"level":"info","ts":"2026-04-25T14:17:38.766055Z","caller":"traceutil/trace.go:171","msg":"trace[1561890823] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"113.631707ms","start":"2026-04-25T14:17:38.652401Z","end":"2026-04-25T14:17:38.766033Z","steps":["trace[1561890823] 'process raft request' (duration: 113.356864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:17:38.766241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.801172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-25T14:17:38.766403Z","caller":"traceutil/trace.go:171","msg":"trace[298178809] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:2; }","duration":"112.982779ms","start":"2026-04-25T14:17:38.653406Z","end":"2026-04-25T14:17:38.766389Z","steps":["trace[298178809] 'agreement among raft nodes before linearized reading' (duration: 112.715548ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:17:38.890048Z","caller":"traceutil/trace.go:171","msg":"trace[2006402224] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"153.684306ms","start":"2026-04-25T14:17:38.736334Z","end":"2026-04-25T14:17:38.890019Z","steps":["trace[2006402224] 'process raft request' (duration: 153.506788ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:17:38.890097Z","caller":"traceutil/trace.go:171","msg":"trace[1506482250] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"153.689166ms","start":"2026-04-25T14:17:38.736386Z","end":"2026-04-25T14:17:38.890076Z","steps":["trace[1506482250] 'process raft request' (duration: 153.574601ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:17:38.890231Z","caller":"traceutil/trace.go:171","msg":"trace[1797321689] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"144.682839ms","start":"2026-04-25T14:17:38.745534Z","end":"2026-04-25T14:17:38.890217Z","steps":["trace[1797321689] 'process raft request' (duration: 144.593525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:17:38.890305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.316163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-25T14:17:38.890393Z","caller":"traceutil/trace.go:171","msg":"trace[731519441] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"154.435648ms","start":"2026-04-25T14:17:38.735944Z","end":"2026-04-25T14:17:38.89038Z","steps":["trace[731519441] 'agreement among raft nodes before linearized reading' (duration: 154.283101ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:17:38.890416Z","caller":"traceutil/trace.go:171","msg":"trace[599342218] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"153.855494ms","start":"2026-04-25T14:17:38.736543Z","end":"2026-04-25T14:17:38.890399Z","steps":["trace[599342218] 'process raft request' (duration: 153.457517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:17:38.890417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.041398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-25T14:17:38.890455Z","caller":"traceutil/trace.go:171","msg":"trace[837944156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:10; }","duration":"235.100172ms","start":"2026-04-25T14:17:38.655345Z","end":"2026-04-25T14:17:38.890445Z","steps":["trace[837944156] 'agreement among raft nodes before linearized reading' (duration: 235.017568ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:17:38.890494Z","caller":"traceutil/trace.go:171","msg":"trace[1439318521] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"153.866704ms","start":"2026-04-25T14:17:38.736619Z","end":"2026-04-25T14:17:38.890486Z","steps":["trace[1439318521] 'process raft request' (duration: 153.404695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:17:38.890446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.597181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-25T14:17:38.890574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.018358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-25T14:17:38.890635Z","caller":"traceutil/trace.go:171","msg":"trace[1979606171] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"183.832452ms","start":"2026-04-25T14:17:38.706783Z","end":"2026-04-25T14:17:38.890615Z","steps":["trace[1979606171] 'agreement among raft nodes before linearized reading' (duration: 183.570781ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:17:38.890652Z","caller":"traceutil/trace.go:171","msg":"trace[809099127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:10; }","duration":"235.148413ms","start":"2026-04-25T14:17:38.655486Z","end":"2026-04-25T14:17:38.890634Z","steps":["trace[809099127] 'agreement among raft nodes before linearized reading' (duration: 234.873342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:17:38.890715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.718189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-25T14:17:38.89075Z","caller":"traceutil/trace.go:171","msg":"trace[1499523401] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"154.782262ms","start":"2026-04-25T14:17:38.735958Z","end":"2026-04-25T14:17:38.89074Z","steps":["trace[1499523401] 'agreement among raft nodes before linearized reading' (duration: 154.7332ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:17:38.89076Z","caller":"traceutil/trace.go:171","msg":"trace[669449087] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"154.0188ms","start":"2026-04-25T14:17:38.736727Z","end":"2026-04-25T14:17:38.890746Z","steps":["trace[669449087] 'process raft request' (duration: 153.356612ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:17:38.890583Z","caller":"traceutil/trace.go:171","msg":"trace[199093105] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"153.871054ms","start":"2026-04-25T14:17:38.736699Z","end":"2026-04-25T14:17:38.890571Z","steps":["trace[199093105] 'process raft request' (duration: 153.358092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:17:38.890889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.092055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-25T14:17:38.89099Z","caller":"traceutil/trace.go:171","msg":"trace[39081570] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:0; response_revision:10; }","duration":"155.21038ms","start":"2026-04-25T14:17:38.735749Z","end":"2026-04-25T14:17:38.890959Z","steps":["trace[39081570] 'agreement among raft nodes before linearized reading' (duration: 155.068154ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:17:38.890236Z","caller":"traceutil/trace.go:171","msg":"trace[1986968136] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"138.334472ms","start":"2026-04-25T14:17:38.751889Z","end":"2026-04-25T14:17:38.890223Z","steps":["trace[1986968136] 'process raft request' (duration: 138.2707ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:17:38.891101Z","caller":"traceutil/trace.go:171","msg":"trace[1701440496] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"123.990701ms","start":"2026-04-25T14:17:38.766198Z","end":"2026-04-25T14:17:38.890188Z","steps":["trace[1701440496] 'read index received' (duration: 123.566463ms)","trace[1701440496] 'applied index is now lower than readState.Index' (duration: 423.288µs)"],"step_count":2} {"level":"warn","ts":"2026-04-25T14:17:38.891195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.949628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-25T14:17:38.892896Z","caller":"traceutil/trace.go:171","msg":"trace[848664868] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:10; }","duration":"238.66202ms","start":"2026-04-25T14:17:38.654223Z","end":"2026-04-25T14:17:38.892885Z","steps":["trace[848664868] 'agreement among raft nodes before linearized reading' (duration: 236.940187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:17:38.892987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.270748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-25T14:17:38.893031Z","caller":"traceutil/trace.go:171","msg":"trace[456954862] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"106.33029ms","start":"2026-04-25T14:17:38.786689Z","end":"2026-04-25T14:17:38.893019Z","steps":["trace[456954862] 'agreement among raft nodes before linearized reading' (duration: 106.276237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:17:38.892941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.978729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-25T14:17:38.893479Z","caller":"traceutil/trace.go:171","msg":"trace[1887574483] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"113.566164ms","start":"2026-04-25T14:17:38.779901Z","end":"2026-04-25T14:17:38.893467Z","steps":["trace[1887574483] 'agreement among raft nodes before linearized reading' (duration: 112.9907ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:18:02.094736Z","caller":"traceutil/trace.go:171","msg":"trace[812440773] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"125.76895ms","start":"2026-04-25T14:18:01.968943Z","end":"2026-04-25T14:18:02.094712Z","steps":["trace[812440773] 'process raft request' (duration: 125.612374ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:18:02.101602Z","caller":"traceutil/trace.go:171","msg":"trace[1029944685] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"113.735788ms","start":"2026-04-25T14:18:01.987842Z","end":"2026-04-25T14:18:02.101578Z","steps":["trace[1029944685] 'process raft request' (duration: 113.5412ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:18:02.101899Z","caller":"traceutil/trace.go:171","msg":"trace[1191404751] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"130.194473ms","start":"2026-04-25T14:18:01.971695Z","end":"2026-04-25T14:18:02.101889Z","steps":["trace[1191404751] 'process raft request' (duration: 129.578495ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:18:02.10201Z","caller":"traceutil/trace.go:171","msg":"trace[1984000345] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"130.246325ms","start":"2026-04-25T14:18:01.971755Z","end":"2026-04-25T14:18:02.102001Z","steps":["trace[1984000345] 'process raft request' (duration: 129.574015ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:18:02.102019Z","caller":"traceutil/trace.go:171","msg":"trace[350448308] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"113.604093ms","start":"2026-04-25T14:18:01.988369Z","end":"2026-04-25T14:18:02.101973Z","steps":["trace[350448308] 'process raft request' (duration: 113.040218ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:18:02.102093Z","caller":"traceutil/trace.go:171","msg":"trace[220582504] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"114.342315ms","start":"2026-04-25T14:18:01.987742Z","end":"2026-04-25T14:18:02.102085Z","steps":["trace[220582504] 'process raft request' (duration: 113.613523ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:18:02.102361Z","caller":"traceutil/trace.go:171","msg":"trace[28186637] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"113.901427ms","start":"2026-04-25T14:18:01.988451Z","end":"2026-04-25T14:18:02.102353Z","steps":["trace[28186637] 'process raft request' (duration: 112.980586ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:18:02.10307Z","caller":"traceutil/trace.go:171","msg":"trace[1843791683] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"114.499792ms","start":"2026-04-25T14:18:01.988561Z","end":"2026-04-25T14:18:02.103061Z","steps":["trace[1843791683] 'process raft request' (duration: 112.898092ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:18:02.103301Z","caller":"traceutil/trace.go:171","msg":"trace[272814845] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"105.605286ms","start":"2026-04-25T14:18:01.997683Z","end":"2026-04-25T14:18:02.103288Z","steps":["trace[272814845] 'process raft request' (duration: 103.853429ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:18:02.103466Z","caller":"traceutil/trace.go:171","msg":"trace[513123805] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"107.07542ms","start":"2026-04-25T14:18:01.996373Z","end":"2026-04-25T14:18:02.103448Z","steps":["trace[513123805] 'process raft request' (duration: 105.124075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:18:08.659216Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725843508120479,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-25T14:18:08.831394Z","caller":"traceutil/trace.go:171","msg":"trace[1587343600] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"1.01022545s","start":"2026-04-25T14:18:07.821146Z","end":"2026-04-25T14:18:08.831371Z","steps":["trace[1587343600] 'process raft request' (duration: 1.010059522s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:18:08.831997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:18:07.821109Z","time spent":"1.010421359s","remote":"127.0.0.1:43636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-25T14:18:08.849393Z","caller":"traceutil/trace.go:171","msg":"trace[139065719] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:588; }","duration":"690.428429ms","start":"2026-04-25T14:18:08.158887Z","end":"2026-04-25T14:18:08.849315Z","steps":["trace[139065719] 'read index received' (duration: 672.351601ms)","trace[139065719] 'applied index is now lower than readState.Index' (duration: 18.075318ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T14:18:08.849674Z","caller":"traceutil/trace.go:171","msg":"trace[135748789] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"726.486078ms","start":"2026-04-25T14:18:08.123022Z","end":"2026-04-25T14:18:08.849508Z","steps":["trace[135748789] 'process raft request' (duration: 723.966809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:18:08.849786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.933283ms","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-25T14:18:08.849869Z","caller":"traceutil/trace.go:171","msg":"trace[851227524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:568; }","duration":"691.032152ms","start":"2026-04-25T14:18:08.158795Z","end":"2026-04-25T14:18:08.849827Z","steps":["trace[851227524] 'agreement among raft nodes before linearized reading' (duration: 690.819245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:18:08.849932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:18:08.158778Z","time spent":"691.11907ms","remote":"127.0.0.1:43710","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-25T14:18:08.849893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:18:08.122993Z","time spent":"726.734455ms","remote":"127.0.0.1:43548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T14:18:08.850628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.416944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T14:18:08.850712Z","caller":"traceutil/trace.go:171","msg":"trace[1874003082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"608.540543ms","start":"2026-04-25T14:18:08.242148Z","end":"2026-04-25T14:18:08.850689Z","steps":["trace[1874003082] 'agreement among raft nodes before linearized reading' (duration: 608.382205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:18:08.850777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:18:08.242135Z","time spent":"608.631931ms","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-25T14:19:18.767689Z","caller":"traceutil/trace.go:171","msg":"trace[426552273] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"276.668909ms","start":"2026-04-25T14:19:18.490993Z","end":"2026-04-25T14:19:18.767662Z","steps":["trace[426552273] 'process raft request' (duration: 276.507136ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:19:19.408715Z","caller":"traceutil/trace.go:171","msg":"trace[2029369886] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"428.714317ms","start":"2026-04-25T14:19:18.979983Z","end":"2026-04-25T14:19:19.408698Z","steps":["trace[2029369886] 'process raft request' (duration: 398.752572ms)","trace[2029369886] 'compare' (duration: 29.688649ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T14:19:19.408809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:18.979968Z","time spent":"428.797068ms","remote":"127.0.0.1:43532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-25T14:19:19.435244Z","caller":"traceutil/trace.go:171","msg":"trace[1528660740] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1117; }","duration":"227.322203ms","start":"2026-04-25T14:19:19.207895Z","end":"2026-04-25T14:19:19.435217Z","steps":["trace[1528660740] 'read index received' (duration: 170.83964ms)","trace[1528660740] 'applied index is now lower than readState.Index' (duration: 56.481623ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T14:19:19.435814Z","caller":"traceutil/trace.go:171","msg":"trace[1113559260] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"353.868198ms","start":"2026-04-25T14:19:19.08193Z","end":"2026-04-25T14:19:19.435798Z","steps":["trace[1113559260] 'process raft request' (duration: 353.105391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:19.436093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:19.081901Z","time spent":"354.112694ms","remote":"127.0.0.1:43710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T14:19:19.436434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.537391ms","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-25T14:19:19.43647Z","caller":"traceutil/trace.go:171","msg":"trace[591315525] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1079; }","duration":"228.598562ms","start":"2026-04-25T14:19:19.207861Z","end":"2026-04-25T14:19:19.436459Z","steps":["trace[591315525] 'agreement among raft nodes before linearized reading' (duration: 228.464529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:19.436514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.762703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T14:19:19.436599Z","caller":"traceutil/trace.go:171","msg":"trace[503348418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1079; }","duration":"194.894036ms","start":"2026-04-25T14:19:19.241676Z","end":"2026-04-25T14:19:19.43657Z","steps":["trace[503348418] 'agreement among raft nodes before linearized reading' (duration: 194.753413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:19.436656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.594154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-25T14:19:19.436746Z","caller":"traceutil/trace.go:171","msg":"trace[1008892717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1079; }","duration":"126.725547ms","start":"2026-04-25T14:19:19.309998Z","end":"2026-04-25T14:19:19.436723Z","steps":["trace[1008892717] 'agreement among raft nodes before linearized reading' (duration: 126.449871ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:19:19.58594Z","caller":"traceutil/trace.go:171","msg":"trace[1062675104] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"145.145949ms","start":"2026-04-25T14:19:19.440769Z","end":"2026-04-25T14:19:19.585915Z","steps":["trace[1062675104] 'process raft request' (duration: 70.593052ms)","trace[1062675104] 'compare' (duration: 74.462885ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T14:19:19.586089Z","caller":"traceutil/trace.go:171","msg":"trace[14492218] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1119; }","duration":"116.34988ms","start":"2026-04-25T14:19:19.469686Z","end":"2026-04-25T14:19:19.586036Z","steps":["trace[14492218] 'read index received' (duration: 13.830022ms)","trace[14492218] 'applied index is now lower than readState.Index' (duration: 102.514168ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T14:19:19.586336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.624946ms","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-25T14:19:19.586421Z","caller":"traceutil/trace.go:171","msg":"trace[1443646749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1081; }","duration":"116.742379ms","start":"2026-04-25T14:19:19.46966Z","end":"2026-04-25T14:19:19.586402Z","steps":["trace[1443646749] 'agreement among raft nodes before linearized reading' (duration: 116.492793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:20.407968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.140904ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-25T14:19:20.408535Z","caller":"traceutil/trace.go:171","msg":"trace[1721947879] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"816.614269ms","start":"2026-04-25T14:19:19.591859Z","end":"2026-04-25T14:19:20.408473Z","steps":["trace[1721947879] 'process raft request' (duration: 94.715629ms)","trace[1721947879] 'compare' (duration: 720.966309ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T14:19:20.408845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:19.591841Z","time spent":"816.773132ms","remote":"127.0.0.1:43710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T14:19:20.780276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.295724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-25T14:19:20.780407Z","caller":"traceutil/trace.go:171","msg":"trace[807387507] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1121; }","duration":"1.175641635s","start":"2026-04-25T14:19:19.604752Z","end":"2026-04-25T14:19:20.780393Z","steps":["trace[807387507] 'read index received' (duration: 81.824047ms)","trace[807387507] 'applied index is now lower than readState.Index' (duration: 1.093816478s)"],"step_count":2} {"level":"info","ts":"2026-04-25T14:19:20.780495Z","caller":"traceutil/trace.go:171","msg":"trace[1601086524] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"1.188600028s","start":"2026-04-25T14:19:19.591879Z","end":"2026-04-25T14:19:20.780479Z","steps":["trace[1601086524] 'process raft request' (duration: 941.034917ms)","trace[1601086524] 'compare' (duration: 247.213662ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T14:19:20.78056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:19.591863Z","time spent":"1.18867408s","remote":"127.0.0.1:43710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T14:19:20.780722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.288897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T14:19:20.780826Z","caller":"traceutil/trace.go:171","msg":"trace[686567112] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1083; }","duration":"870.4344ms","start":"2026-04-25T14:19:19.910361Z","end":"2026-04-25T14:19:20.780795Z","steps":["trace[686567112] 'agreement among raft nodes before linearized reading' (duration: 870.285336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:20.780845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.106769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-25T14:19:20.780879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:19.910332Z","time spent":"870.536252ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-25T14:19:20.780923Z","caller":"traceutil/trace.go:171","msg":"trace[795697553] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:1083; }","duration":"475.283593ms","start":"2026-04-25T14:19:20.305611Z","end":"2026-04-25T14:19:20.780894Z","steps":["trace[795697553] 'agreement among raft nodes before linearized reading' (duration: 475.12953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:20.780933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176163656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T14:19:20.781013Z","caller":"traceutil/trace.go:171","msg":"trace[1559403358] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1083; }","duration":"1.176246638s","start":"2026-04-25T14:19:19.604745Z","end":"2026-04-25T14:19:20.780992Z","steps":["trace[1559403358] 'agreement among raft nodes before linearized reading' (duration: 1.176144646s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:20.78106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:19.604693Z","time spent":"1.17635794s","remote":"127.0.0.1:43598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-25T14:19:20.780963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:20.305586Z","time spent":"475.370744ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-04-25T14:19:20.781107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.060301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T14:19:20.781193Z","caller":"traceutil/trace.go:171","msg":"trace[17519992] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1083; }","duration":"567.213774ms","start":"2026-04-25T14:19:20.213958Z","end":"2026-04-25T14:19:20.781172Z","steps":["trace[17519992] 'agreement among raft nodes before linearized reading' (duration: 567.091531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:20.781267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:20.213923Z","time spent":"567.311836ms","remote":"127.0.0.1:37822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-04-25T14:19:21.110582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.391171ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-25T14:19:21.110826Z","caller":"traceutil/trace.go:171","msg":"trace[1262003459] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1123; }","duration":"325.506671ms","start":"2026-04-25T14:19:20.785303Z","end":"2026-04-25T14:19:21.11081Z","steps":["trace[1262003459] 'read index received' (duration: 140.970245ms)","trace[1262003459] 'applied index is now lower than readState.Index' (duration: 184.535515ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T14:19:21.110913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.599562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T14:19:21.110898Z","caller":"traceutil/trace.go:171","msg":"trace[1726273002] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"325.868729ms","start":"2026-04-25T14:19:20.784997Z","end":"2026-04-25T14:19:21.110866Z","steps":["trace[1726273002] 'process raft request' (duration: 325.674183ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:19:21.110956Z","caller":"traceutil/trace.go:171","msg":"trace[602861090] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1085; }","duration":"325.650644ms","start":"2026-04-25T14:19:20.785285Z","end":"2026-04-25T14:19:21.110936Z","steps":["trace[602861090] 'agreement among raft nodes before linearized reading' (duration: 325.578013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:21.110994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:20.785277Z","time spent":"325.709395ms","remote":"127.0.0.1:43598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-25T14:19:21.110853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:20.782043Z","time spent":"328.800823ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-25T14:19:21.111057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:20.784982Z","time spent":"325.984261ms","remote":"127.0.0.1:43710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-25T14:19:21.112249Z","caller":"traceutil/trace.go:171","msg":"trace[1812908047] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"258.331906ms","start":"2026-04-25T14:19:20.853872Z","end":"2026-04-25T14:19:21.112204Z","steps":["trace[1812908047] 'process raft request' (duration: 256.902115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:21.446005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.369092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-25T14:19:21.446098Z","caller":"traceutil/trace.go:171","msg":"trace[1869385300] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1126; }","duration":"203.939978ms","start":"2026-04-25T14:19:21.242144Z","end":"2026-04-25T14:19:21.446084Z","steps":["trace[1869385300] 'read index received' (duration: 10.382703ms)","trace[1869385300] 'applied index is now lower than readState.Index' (duration: 193.556045ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T14:19:21.446153Z","caller":"traceutil/trace.go:171","msg":"trace[1963641187] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"332.968823ms","start":"2026-04-25T14:19:21.113173Z","end":"2026-04-25T14:19:21.446141Z","steps":["trace[1963641187] 'process raft request' (duration: 139.500199ms)","trace[1963641187] 'compare' (duration: 193.157127ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T14:19:21.446207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:21.113132Z","time spent":"333.045174ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T14:19:21.446608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.448449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-25T14:19:21.446683Z","caller":"traceutil/trace.go:171","msg":"trace[258676831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1086; }","duration":"204.580612ms","start":"2026-04-25T14:19:21.242085Z","end":"2026-04-25T14:19:21.446666Z","steps":["trace[258676831] 'agreement among raft nodes before linearized reading' (duration: 204.130462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:21.475807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.390064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-25T14:19:21.475877Z","caller":"traceutil/trace.go:171","msg":"trace[997786246] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1087; }","duration":"189.494135ms","start":"2026-04-25T14:19:21.286366Z","end":"2026-04-25T14:19:21.47586Z","steps":["trace[997786246] 'agreement among raft nodes before linearized reading' (duration: 189.350522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:21.868351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.949482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-25T14:19:21.868431Z","caller":"traceutil/trace.go:171","msg":"trace[259356414] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1088; }","duration":"278.079615ms","start":"2026-04-25T14:19:21.590332Z","end":"2026-04-25T14:19:21.868412Z","steps":["trace[259356414] 'range keys from in-memory index tree' (duration: 277.736497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:22.729365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725843508121641,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-25T14:19:23.217308Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.344679352s","expected-duration":"1s"} {"level":"info","ts":"2026-04-25T14:19:23.217701Z","caller":"traceutil/trace.go:171","msg":"trace[758709448] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"989.270483ms","start":"2026-04-25T14:19:22.228406Z","end":"2026-04-25T14:19:23.217676Z","steps":["trace[758709448] 'read index received' (duration: 989.089348ms)","trace[758709448] 'applied index is now lower than readState.Index' (duration: 180.215µs)"],"step_count":2} {"level":"warn","ts":"2026-04-25T14:19:23.217899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.469768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-25T14:19:23.217967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.979118ms","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-25T14:19:23.217979Z","caller":"traceutil/trace.go:171","msg":"trace[1221048] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1089; }","duration":"989.603971ms","start":"2026-04-25T14:19:22.228341Z","end":"2026-04-25T14:19:23.217945Z","steps":["trace[1221048] 'agreement among raft nodes before linearized reading' (duration: 989.465447ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:19:23.218018Z","caller":"traceutil/trace.go:171","msg":"trace[1102956291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1089; }","duration":"805.06939ms","start":"2026-04-25T14:19:22.412932Z","end":"2026-04-25T14:19:23.218001Z","steps":["trace[1102956291] 'agreement among raft nodes before linearized reading' (duration: 804.901456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:23.218034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:22.228312Z","time spent":"989.708062ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-04-25T14:19:23.218086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:22.412913Z","time spent":"805.143511ms","remote":"127.0.0.1:43710","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-25T14:19:23.218152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.769587ms","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-25T14:19:23.218217Z","caller":"traceutil/trace.go:171","msg":"trace[1451528651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1089; }","duration":"433.864419ms","start":"2026-04-25T14:19:22.784324Z","end":"2026-04-25T14:19:23.218188Z","steps":["trace[1451528651] 'agreement among raft nodes before linearized reading' (duration: 433.666215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:23.218263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:22.784308Z","time spent":"433.945901ms","remote":"127.0.0.1:43710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-25T14:19:23.21777Z","caller":"traceutil/trace.go:171","msg":"trace[1272770134] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"1.345225815s","start":"2026-04-25T14:19:21.872523Z","end":"2026-04-25T14:19:23.217749Z","steps":["trace[1272770134] 'process raft request' (duration: 1.344966079s)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:23.218001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.549643ms","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":"warn","ts":"2026-04-25T14:19:23.218565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:21.872498Z","time spent":"1.346006763s","remote":"127.0.0.1:43710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-25T14:19:23.218648Z","caller":"traceutil/trace.go:171","msg":"trace[1532322943] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1089; }","duration":"103.227129ms","start":"2026-04-25T14:19:23.115384Z","end":"2026-04-25T14:19:23.218611Z","steps":["trace[1532322943] 'agreement among raft nodes before linearized reading' (duration: 102.480302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:23.866001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.169589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-25T14:19:23.866237Z","caller":"traceutil/trace.go:171","msg":"trace[595719895] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1130; }","duration":"625.332028ms","start":"2026-04-25T14:19:23.240875Z","end":"2026-04-25T14:19:23.866207Z","steps":["trace[595719895] 'read index received' (duration: 278.839091ms)","trace[595719895] 'applied index is now lower than readState.Index' (duration: 346.492067ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T14:19:23.866347Z","caller":"traceutil/trace.go:171","msg":"trace[1975471582] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"644.824453ms","start":"2026-04-25T14:19:23.221486Z","end":"2026-04-25T14:19:23.866311Z","steps":["trace[1975471582] 'process raft request' (duration: 644.614208ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:19:23.866375Z","caller":"traceutil/trace.go:171","msg":"trace[708762920] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"385.053557ms","start":"2026-04-25T14:19:23.481289Z","end":"2026-04-25T14:19:23.866342Z","steps":["trace[708762920] 'process raft request' (duration: 384.886263ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:19:23.866461Z","caller":"traceutil/trace.go:171","msg":"trace[1190624524] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"643.82637ms","start":"2026-04-25T14:19:23.222623Z","end":"2026-04-25T14:19:23.866449Z","steps":["trace[1190624524] 'process raft request' (duration: 643.523603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:23.866505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:23.221466Z","time spent":"644.957866ms","remote":"127.0.0.1:43710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T14:19:23.866522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:23.222612Z","time spent":"643.877541ms","remote":"127.0.0.1:43710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-25T14:19:23.866411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.539632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-25T14:19:23.866541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.243734ms","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-25T14:19:23.866518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:23.481262Z","time spent":"385.18324ms","remote":"127.0.0.1:43710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-25T14:19:23.866567Z","caller":"traceutil/trace.go:171","msg":"trace[1450630382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"625.733777ms","start":"2026-04-25T14:19:23.240823Z","end":"2026-04-25T14:19:23.866557Z","steps":["trace[1450630382] 'agreement among raft nodes before linearized reading' (duration: 625.502991ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:19:23.866588Z","caller":"traceutil/trace.go:171","msg":"trace[1567613242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1093; }","duration":"319.347176ms","start":"2026-04-25T14:19:23.547227Z","end":"2026-04-25T14:19:23.866574Z","steps":["trace[1567613242] 'agreement among raft nodes before linearized reading' (duration: 319.204713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:23.866602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:23.240804Z","time spent":"625.790447ms","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-25T14:19:23.866589Z","caller":"traceutil/trace.go:171","msg":"trace[870081539] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"645.234052ms","start":"2026-04-25T14:19:23.221323Z","end":"2026-04-25T14:19:23.866557Z","steps":["trace[870081539] 'process raft request' (duration: 298.419248ms)","trace[870081539] 'compare' (duration: 346.064797ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T14:19:23.866624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:23.547214Z","time spent":"319.402598ms","remote":"127.0.0.1:43710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-25T14:19:23.866727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-25T14:19:23.221299Z","time spent":"645.354945ms","remote":"127.0.0.1:43710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-25T14:19:28.019649Z","caller":"traceutil/trace.go:171","msg":"trace[1532059033] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"131.408651ms","start":"2026-04-25T14:19:27.888219Z","end":"2026-04-25T14:19:28.019627Z","steps":["trace[1532059033] 'process raft request' (duration: 88.333622ms)","trace[1532059033] 'compare' (duration: 42.941646ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T14:19:28.019954Z","caller":"traceutil/trace.go:171","msg":"trace[1448621799] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"131.105664ms","start":"2026-04-25T14:19:27.888835Z","end":"2026-04-25T14:19:28.01994Z","steps":["trace[1448621799] 'process raft request' (duration: 130.753155ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:19:28.019938Z","caller":"traceutil/trace.go:171","msg":"trace[940955642] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1148; }","duration":"128.134193ms","start":"2026-04-25T14:19:27.891781Z","end":"2026-04-25T14:19:28.019915Z","steps":["trace[940955642] 'read index received' (duration: 40.849452ms)","trace[940955642] 'applied index is now lower than readState.Index' (duration: 87.283871ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T14:19:28.020007Z","caller":"traceutil/trace.go:171","msg":"trace[1505531909] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"130.167761ms","start":"2026-04-25T14:19:27.88981Z","end":"2026-04-25T14:19:28.019978Z","steps":["trace[1505531909] 'process raft request' (duration: 130.017048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:28.020042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.249556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-25T14:19:28.020091Z","caller":"traceutil/trace.go:171","msg":"trace[2106328754] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:1; response_revision:1110; }","duration":"128.329878ms","start":"2026-04-25T14:19:27.891747Z","end":"2026-04-25T14:19:28.020077Z","steps":["trace[2106328754] 'agreement among raft nodes before linearized reading' (duration: 128.240676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:28.020191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.451382ms","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-25T14:19:28.020237Z","caller":"traceutil/trace.go:171","msg":"trace[1685060850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1110; }","duration":"118.524414ms","start":"2026-04-25T14:19:27.901701Z","end":"2026-04-25T14:19:28.020225Z","steps":["trace[1685060850] 'agreement among raft nodes before linearized reading' (duration: 118.390571ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:19:42.125208Z","caller":"traceutil/trace.go:171","msg":"trace[1021423582] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1198; }","duration":"115.974185ms","start":"2026-04-25T14:19:42.009209Z","end":"2026-04-25T14:19:42.125183Z","steps":["trace[1021423582] 'read index received' (duration: 115.822261ms)","trace[1021423582] 'applied index is now lower than readState.Index' (duration: 150.844µs)"],"step_count":2} {"level":"info","ts":"2026-04-25T14:19:42.12532Z","caller":"traceutil/trace.go:171","msg":"trace[184100640] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"121.904195ms","start":"2026-04-25T14:19:42.003379Z","end":"2026-04-25T14:19:42.125283Z","steps":["trace[184100640] 'process raft request' (duration: 121.658368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-25T14:19:42.125535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.295244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-oidc-hmac\" ","response":"range_response_count:1 size:452"} {"level":"info","ts":"2026-04-25T14:19:42.125692Z","caller":"traceutil/trace.go:171","msg":"trace[2131168436] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-oidc-hmac; range_end:; response_count:1; response_revision:1154; }","duration":"116.423127ms","start":"2026-04-25T14:19:42.00918Z","end":"2026-04-25T14:19:42.125603Z","steps":["trace[2131168436] 'agreement among raft nodes before linearized reading' (duration: 116.13417ms)"],"step_count":1} {"level":"info","ts":"2026-04-25T14:19:44.254686Z","caller":"traceutil/trace.go:171","msg":"trace[212495676] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1205; }","duration":"113.50048ms","start":"2026-04-25T14:19:44.14116Z","end":"2026-04-25T14:19:44.25466Z","steps":["trace[212495676] 'read index received' (duration: 68.807023ms)","trace[212495676] 'applied index is now lower than readState.Index' (duration: 44.692176ms)"],"step_count":2} {"level":"info","ts":"2026-04-25T14:19:44.254837Z","caller":"traceutil/trace.go:171","msg":"trace[1633706909] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"125.425825ms","start":"2026-04-25T14:19:44.129394Z","end":"2026-04-25T14:19:44.25482Z","steps":["trace[1633706909] 'process raft request' (duration: 93.134896ms)","trace[1633706909] 'compare' (duration: 32.017531ms)"],"step_count":2} {"level":"warn","ts":"2026-04-25T14:19:44.255527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.350112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/kube-root-ca.crt\" ","response":"range_response_count:1 size:1760"} {"level":"info","ts":"2026-04-25T14:19:44.255655Z","caller":"traceutil/trace.go:171","msg":"trace[1494503875] range","detail":"{range_begin:/registry/configmaps/local-path-storage/kube-root-ca.crt; range_end:; response_count:1; response_revision:1161; }","duration":"114.491315ms","start":"2026-04-25T14:19:44.141134Z","end":"2026-04-25T14:19:44.255625Z","steps":["trace[1494503875] 'agreement among raft nodes before linearized reading' (duration: 114.247839ms)"],"step_count":1}