{"level":"warn","ts":"2026-04-08T12:56:33.812308Z","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-08T12:56:33.812394Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.25: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.25:2380","--initial-cluster=instance=https://199.19.213.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.25: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-08T12:56:33.812462Z","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-08T12:56:33.812478Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.25:2380"]} {"level":"info","ts":"2026-04-08T12:56:33.8125Z","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-08T12:56:33.813282Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.25:2379"]} {"level":"info","ts":"2026-04-08T12:56:33.813405Z","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.25:2380"],"listen-peer-urls":["https://199.19.213.25:2380"],"advertise-client-urls":["https://199.19.213.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.25: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-08T12:56:33.828898Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.940391ms"} {"level":"info","ts":"2026-04-08T12:56:34.101929Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd4db28c48efaf1","cluster-id":"b621f59192c78404"} {"level":"info","ts":"2026-04-08T12:56:34.102021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T12:56:34.102173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 became follower at term 0"} {"level":"info","ts":"2026-04-08T12:56:34.102198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd4db28c48efaf1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T12:56:34.102209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 became follower at term 1"} {"level":"info","ts":"2026-04-08T12:56:34.102263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 switched to configuration voters=(11228840739084368625)"} {"level":"warn","ts":"2026-04-08T12:56:34.212445Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T12:56:34.257517Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T12:56:34.279479Z","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-08T12:56:34.308425Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd4db28c48efaf1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T12:56:34.308762Z","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-08T12:56:34.308723Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd4db28c48efaf1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T12:56:34.308822Z","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-08T12:56:34.308933Z","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-08T12:56:34.314096Z","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-08T12:56:34.314316Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd4db28c48efaf1","initial-advertise-peer-urls":["https://199.19.213.25:2380"],"listen-peer-urls":["https://199.19.213.25:2380"],"advertise-client-urls":["https://199.19.213.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T12:56:34.314349Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T12:56:34.314449Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.25:2380"} {"level":"info","ts":"2026-04-08T12:56:34.314465Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.25:2380"} {"level":"info","ts":"2026-04-08T12:56:34.375238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 switched to configuration voters=(11228840739084368625)"} {"level":"info","ts":"2026-04-08T12:56:34.375432Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b621f59192c78404","local-member-id":"9bd4db28c48efaf1","added-peer-id":"9bd4db28c48efaf1","added-peer-peer-urls":["https://199.19.213.25:2380"]} {"level":"info","ts":"2026-04-08T12:56:35.202538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T12:56:35.202651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T12:56:35.202681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 received MsgPreVoteResp from 9bd4db28c48efaf1 at term 1"} {"level":"info","ts":"2026-04-08T12:56:35.202702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 became candidate at term 2"} {"level":"info","ts":"2026-04-08T12:56:35.202713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 received MsgVoteResp from 9bd4db28c48efaf1 at term 2"} {"level":"info","ts":"2026-04-08T12:56:35.20273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 became leader at term 2"} {"level":"info","ts":"2026-04-08T12:56:35.202746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd4db28c48efaf1 elected leader 9bd4db28c48efaf1 at term 2"} {"level":"info","ts":"2026-04-08T12:56:35.318971Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:56:35.476166Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd4db28c48efaf1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.25:2379]}","request-path":"/0/members/9bd4db28c48efaf1/attributes","cluster-id":"b621f59192c78404","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T12:56:35.476209Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T12:56:35.476531Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T12:56:35.476657Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T12:56:35.476465Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T12:56:35.479391Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.25:2379"} {"level":"info","ts":"2026-04-08T12:56:35.480599Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T12:56:35.485312Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b621f59192c78404","local-member-id":"9bd4db28c48efaf1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:56:35.485412Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:56:35.485449Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-08T12:56:38.060293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.543831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-08T12:56:38.060538Z","caller":"traceutil/trace.go:171","msg":"trace[1660751076] linearizableReadLoop","detail":"{readStateIndex:115; appliedIndex:114; }","duration":"240.245928ms","start":"2026-04-08T12:56:37.820242Z","end":"2026-04-08T12:56:38.060488Z","steps":["trace[1660751076] 'read index received' (duration: 7.931019ms)","trace[1660751076] 'applied index is now lower than readState.Index' (duration: 232.313379ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:56:38.060784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.519826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-08T12:56:38.060823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.970062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T12:56:38.060876Z","caller":"traceutil/trace.go:171","msg":"trace[635023419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:110; }","duration":"207.689112ms","start":"2026-04-08T12:56:37.853172Z","end":"2026-04-08T12:56:38.060861Z","steps":["trace[635023419] 'agreement among raft nodes before linearized reading' (duration: 207.511956ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:38.060917Z","caller":"traceutil/trace.go:171","msg":"trace[1459038582] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:110; }","duration":"183.080725ms","start":"2026-04-08T12:56:37.877813Z","end":"2026-04-08T12:56:38.060894Z","steps":["trace[1459038582] 'agreement among raft nodes before linearized reading' (duration: 182.91862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:38.060781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.507097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T12:56:38.061051Z","caller":"traceutil/trace.go:171","msg":"trace[1573121727] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:110; }","duration":"240.814259ms","start":"2026-04-08T12:56:37.820219Z","end":"2026-04-08T12:56:38.061034Z","steps":["trace[1573121727] 'agreement among raft nodes before linearized reading' (duration: 240.383423ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:38.060902Z","caller":"traceutil/trace.go:171","msg":"trace[1561783332] transaction","detail":"{read_only:false; response_revision:110; number_of_response:1; }","duration":"241.376988ms","start":"2026-04-08T12:56:37.819149Z","end":"2026-04-08T12:56:38.060526Z","steps":["trace[1561783332] 'compare' (duration: 231.373184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:39.576126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.805196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T12:56:39.57633Z","caller":"traceutil/trace.go:171","msg":"trace[816987449] linearizableReadLoop","detail":"{readStateIndex:160; appliedIndex:159; }","duration":"181.334416ms","start":"2026-04-08T12:56:39.394968Z","end":"2026-04-08T12:56:39.576303Z","steps":["trace[816987449] 'read index received' (duration: 74.166297ms)","trace[816987449] 'applied index is now lower than readState.Index' (duration: 107.166549ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:56:39.576624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.986562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T12:56:39.576724Z","caller":"traceutil/trace.go:171","msg":"trace[485900051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:155; }","duration":"181.129548ms","start":"2026-04-08T12:56:39.395578Z","end":"2026-04-08T12:56:39.576708Z","steps":["trace[485900051] 'agreement among raft nodes before linearized reading' (duration: 180.979842ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:39.576951Z","caller":"traceutil/trace.go:171","msg":"trace[1772912597] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"229.278104ms","start":"2026-04-08T12:56:39.347644Z","end":"2026-04-08T12:56:39.576922Z","steps":["trace[1772912597] 'process raft request' (duration: 121.608467ms)","trace[1772912597] 'compare' (duration: 106.615289ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:56:39.576756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.73323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-08T12:56:39.577173Z","caller":"traceutil/trace.go:171","msg":"trace[380595199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:155; }","duration":"182.305541ms","start":"2026-04-08T12:56:39.394844Z","end":"2026-04-08T12:56:39.577149Z","steps":["trace[380595199] 'agreement among raft nodes before linearized reading' (duration: 181.541023ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:45.869678Z","caller":"traceutil/trace.go:171","msg":"trace[1694253622] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:353; }","duration":"193.482449ms","start":"2026-04-08T12:56:45.676153Z","end":"2026-04-08T12:56:45.869635Z","steps":["trace[1694253622] 'read index received' (duration: 134.746424ms)","trace[1694253622] 'applied index is now lower than readState.Index' (duration: 58.734865ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:56:45.869821Z","caller":"traceutil/trace.go:171","msg":"trace[2125336588] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"210.174001ms","start":"2026-04-08T12:56:45.659571Z","end":"2026-04-08T12:56:45.869745Z","steps":["trace[2125336588] 'process raft request' (duration: 151.377474ms)","trace[2125336588] 'compare' (duration: 58.574538ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:56:45.869867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.229277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-08T12:56:45.869908Z","caller":"traceutil/trace.go:171","msg":"trace[1607393353] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:342; }","duration":"188.295979ms","start":"2026-04-08T12:56:45.6816Z","end":"2026-04-08T12:56:45.869896Z","steps":["trace[1607393353] 'agreement among raft nodes before linearized reading' (duration: 188.195465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:45.870179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.546863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-08T12:56:45.870238Z","caller":"traceutil/trace.go:171","msg":"trace[280120359] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:342; }","duration":"120.641787ms","start":"2026-04-08T12:56:45.749579Z","end":"2026-04-08T12:56:45.870221Z","steps":["trace[280120359] 'agreement among raft nodes before linearized reading' (duration: 120.528402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:45.870336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.183325ms","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-08T12:56:45.87037Z","caller":"traceutil/trace.go:171","msg":"trace[1740001912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:342; }","duration":"194.242888ms","start":"2026-04-08T12:56:45.676118Z","end":"2026-04-08T12:56:45.870361Z","steps":["trace[1740001912] 'agreement among raft nodes before linearized reading' (duration: 193.663007ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:46.036496Z","caller":"traceutil/trace.go:171","msg":"trace[629523537] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"158.491096ms","start":"2026-04-08T12:56:45.877979Z","end":"2026-04-08T12:56:46.03647Z","steps":["trace[629523537] 'process raft request' (duration: 158.367122ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:46.036677Z","caller":"traceutil/trace.go:171","msg":"trace[326801937] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"157.23332ms","start":"2026-04-08T12:56:45.879422Z","end":"2026-04-08T12:56:46.036655Z","steps":["trace[326801937] 'process raft request' (duration: 157.038433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:46.036688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.395082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-08T12:56:46.036803Z","caller":"traceutil/trace.go:171","msg":"trace[38822924] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:348; }","duration":"155.487106ms","start":"2026-04-08T12:56:45.881244Z","end":"2026-04-08T12:56:46.036731Z","steps":["trace[38822924] 'agreement among raft nodes before linearized reading' (duration: 155.33246ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:46.036533Z","caller":"traceutil/trace.go:171","msg":"trace[1263925060] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:354; }","duration":"155.246567ms","start":"2026-04-08T12:56:45.881272Z","end":"2026-04-08T12:56:46.036518Z","steps":["trace[1263925060] 'read index received' (duration: 30.046512ms)","trace[1263925060] 'applied index is now lower than readState.Index' (duration: 125.198635ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:56:46.036533Z","caller":"traceutil/trace.go:171","msg":"trace[322790128] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"158.511147ms","start":"2026-04-08T12:56:45.878006Z","end":"2026-04-08T12:56:46.036517Z","steps":["trace[322790128] 'process raft request' (duration: 158.411943ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:46.036295Z","caller":"traceutil/trace.go:171","msg":"trace[664461235] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"160.532821ms","start":"2026-04-08T12:56:45.875736Z","end":"2026-04-08T12:56:46.036269Z","steps":["trace[664461235] 'process raft request' (duration: 158.6202ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:46.0372Z","caller":"traceutil/trace.go:171","msg":"trace[745464004] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"160.049673ms","start":"2026-04-08T12:56:45.876363Z","end":"2026-04-08T12:56:46.036412Z","steps":["trace[745464004] 'process raft request' (duration: 159.812994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:46.092928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.174969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-08T12:56:46.092954Z","caller":"traceutil/trace.go:171","msg":"trace[1063875198] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"179.125695ms","start":"2026-04-08T12:56:45.913797Z","end":"2026-04-08T12:56:46.092923Z","steps":["trace[1063875198] 'process raft request' (duration: 178.930188ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:46.093011Z","caller":"traceutil/trace.go:171","msg":"trace[1274214700] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:349; }","duration":"160.309635ms","start":"2026-04-08T12:56:45.932683Z","end":"2026-04-08T12:56:46.092992Z","steps":["trace[1274214700] 'agreement among raft nodes before linearized reading' (duration: 160.154099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:46.093019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.49415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-08T12:56:46.093067Z","caller":"traceutil/trace.go:171","msg":"trace[757110526] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:349; }","duration":"109.571083ms","start":"2026-04-08T12:56:45.983483Z","end":"2026-04-08T12:56:46.093054Z","steps":["trace[757110526] 'agreement among raft nodes before linearized reading' (duration: 109.48312ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:48.373383Z","caller":"traceutil/trace.go:171","msg":"trace[148901069] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"149.297941ms","start":"2026-04-08T12:56:48.224052Z","end":"2026-04-08T12:56:48.37335Z","steps":["trace[148901069] 'process raft request' (duration: 149.080833ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:48.496025Z","caller":"traceutil/trace.go:171","msg":"trace[2079033970] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"271.253767ms","start":"2026-04-08T12:56:48.224689Z","end":"2026-04-08T12:56:48.495943Z","steps":["trace[2079033970] 'process raft request' (duration: 203.592449ms)","trace[2079033970] 'compare' (duration: 67.523463ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:56:53.884016Z","caller":"traceutil/trace.go:171","msg":"trace[1515249653] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"179.761755ms","start":"2026-04-08T12:56:53.704212Z","end":"2026-04-08T12:56:53.883974Z","steps":["trace[1515249653] 'process raft request' (duration: 179.463914ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:54.050041Z","caller":"traceutil/trace.go:171","msg":"trace[1104935853] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"108.012628ms","start":"2026-04-08T12:56:53.942008Z","end":"2026-04-08T12:56:54.050021Z","steps":["trace[1104935853] 'process raft request' (duration: 66.810066ms)","trace[1104935853] 'compare' (duration: 41.104029ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:56:54.35601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.843608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T12:56:54.35613Z","caller":"traceutil/trace.go:171","msg":"trace[900415824] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"214.251924ms","start":"2026-04-08T12:56:54.141861Z","end":"2026-04-08T12:56:54.356113Z","steps":["trace[900415824] 'read index received' (duration: 91.188749ms)","trace[900415824] 'applied index is now lower than readState.Index' (duration: 123.061915ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:56:54.356182Z","caller":"traceutil/trace.go:171","msg":"trace[1527261612] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"214.766273ms","start":"2026-04-08T12:56:54.141369Z","end":"2026-04-08T12:56:54.356136Z","steps":["trace[1527261612] 'process raft request' (duration: 91.724268ms)","trace[1527261612] 'compare' (duration: 122.690392ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:56:54.356292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.42092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-l645w\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-04-08T12:56:54.356328Z","caller":"traceutil/trace.go:171","msg":"trace[2062265292] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-l645w; range_end:; response_count:1; response_revision:425; }","duration":"214.488573ms","start":"2026-04-08T12:56:54.14183Z","end":"2026-04-08T12:56:54.356319Z","steps":["trace[2062265292] 'agreement among raft nodes before linearized reading' (duration: 214.378809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:54.356661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.67006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-04-08T12:56:54.356698Z","caller":"traceutil/trace.go:171","msg":"trace[481582767] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:425; }","duration":"214.714082ms","start":"2026-04-08T12:56:54.141975Z","end":"2026-04-08T12:56:54.356689Z","steps":["trace[481582767] 'agreement among raft nodes before linearized reading' (duration: 214.615328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:54.356826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.06943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3810"} {"level":"info","ts":"2026-04-08T12:56:54.356887Z","caller":"traceutil/trace.go:171","msg":"trace[115124409] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:425; }","duration":"213.171044ms","start":"2026-04-08T12:56:54.143702Z","end":"2026-04-08T12:56:54.356873Z","steps":["trace[115124409] 'agreement among raft nodes before linearized reading' (duration: 213.047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:03.148226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.22053ms","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-08T12:57:03.148314Z","caller":"traceutil/trace.go:171","msg":"trace[14879535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:464; }","duration":"167.357181ms","start":"2026-04-08T12:57:02.980936Z","end":"2026-04-08T12:57:03.148294Z","steps":["trace[14879535] 'range keys from in-memory index tree' (duration: 167.097308ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:04.403792Z","caller":"traceutil/trace.go:171","msg":"trace[1861285895] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"184.240639ms","start":"2026-04-08T12:57:04.219533Z","end":"2026-04-08T12:57:04.403773Z","steps":["trace[1861285895] 'process raft request' (duration: 184.121867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:04.979501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.513416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:57:04.979575Z","caller":"traceutil/trace.go:171","msg":"trace[1850674167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:468; }","duration":"116.648798ms","start":"2026-04-08T12:57:04.862909Z","end":"2026-04-08T12:57:04.979558Z","steps":["trace[1850674167] 'range keys from in-memory index tree' (duration: 116.427514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:04.979671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.644028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12665"} {"level":"info","ts":"2026-04-08T12:57:04.979837Z","caller":"traceutil/trace.go:171","msg":"trace[1874714572] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:468; }","duration":"242.880802ms","start":"2026-04-08T12:57:04.736932Z","end":"2026-04-08T12:57:04.979812Z","steps":["trace[1874714572] 'range keys from in-memory index tree' (duration: 242.472886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:06.258739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.429908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"warn","ts":"2026-04-08T12:57:06.258815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.376518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-04-08T12:57:06.2589Z","caller":"traceutil/trace.go:171","msg":"trace[31189418] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:502; }","duration":"115.651522ms","start":"2026-04-08T12:57:06.143211Z","end":"2026-04-08T12:57:06.258863Z","steps":["trace[31189418] 'range keys from in-memory index tree' (duration: 115.195795ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:06.258926Z","caller":"traceutil/trace.go:171","msg":"trace[1730810048] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:502; }","duration":"115.573381ms","start":"2026-04-08T12:57:06.143323Z","end":"2026-04-08T12:57:06.258896Z","steps":["trace[1730810048] 'range keys from in-memory index tree' (duration: 115.156495ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:07.587559Z","caller":"traceutil/trace.go:171","msg":"trace[645148489] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"181.490412ms","start":"2026-04-08T12:57:07.406041Z","end":"2026-04-08T12:57:07.587531Z","steps":["trace[645148489] 'read index received' (duration: 102.98806ms)","trace[645148489] 'applied index is now lower than readState.Index' (duration: 78.500832ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:57:07.587698Z","caller":"traceutil/trace.go:171","msg":"trace[230634531] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"190.961167ms","start":"2026-04-08T12:57:07.39669Z","end":"2026-04-08T12:57:07.587651Z","steps":["trace[230634531] 'process raft request' (duration: 112.379543ms)","trace[230634531] 'compare' (duration: 78.342881ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:57:07.587758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.687235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:57:07.587804Z","caller":"traceutil/trace.go:171","msg":"trace[1641590089] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:508; }","duration":"181.785387ms","start":"2026-04-08T12:57:07.406005Z","end":"2026-04-08T12:57:07.58779Z","steps":["trace[1641590089] 'agreement among raft nodes before linearized reading' (duration: 181.623105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:08.52273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.18526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T12:57:08.52302Z","caller":"traceutil/trace.go:171","msg":"trace[826604961] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"505.228643ms","start":"2026-04-08T12:57:08.017723Z","end":"2026-04-08T12:57:08.522951Z","steps":["trace[826604961] 'process raft request' (duration: 102.490605ms)","trace[826604961] 'compare' (duration: 401.788525ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:57:08.523238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:08.01769Z","time spent":"505.419637ms","remote":"127.0.0.1:51868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T12:57:08.523434Z","caller":"traceutil/trace.go:171","msg":"trace[1517919400] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"505.570499ms","start":"2026-04-08T12:57:08.017854Z","end":"2026-04-08T12:57:08.523425Z","steps":["trace[1517919400] 'process raft request' (duration: 505.062262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:08.523503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:08.017836Z","time spent":"505.64025ms","remote":"127.0.0.1:51868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T12:57:08.523659Z","caller":"traceutil/trace.go:171","msg":"trace[1203595725] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"502.93217ms","start":"2026-04-08T12:57:08.020643Z","end":"2026-04-08T12:57:08.523575Z","steps":["trace[1203595725] 'process raft request' (duration: 502.673406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:08.523937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:08.020614Z","time spent":"503.177513ms","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T12:57:08.524575Z","caller":"traceutil/trace.go:171","msg":"trace[1369219929] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:560; }","duration":"105.51743ms","start":"2026-04-08T12:57:08.419035Z","end":"2026-04-08T12:57:08.524553Z","steps":["trace[1369219929] 'read index received' (duration: 38.675151ms)","trace[1369219929] 'applied index is now lower than readState.Index' (duration: 66.840579ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:57:08.524821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.798325ms","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-08T12:57:08.524883Z","caller":"traceutil/trace.go:171","msg":"trace[671626136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:541; }","duration":"105.897285ms","start":"2026-04-08T12:57:08.418964Z","end":"2026-04-08T12:57:08.524862Z","steps":["trace[671626136] 'agreement among raft nodes before linearized reading' (duration: 105.673532ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:08.524867Z","caller":"traceutil/trace.go:171","msg":"trace[939937221] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"502.237102ms","start":"2026-04-08T12:57:08.02258Z","end":"2026-04-08T12:57:08.524817Z","steps":["trace[939937221] 'process raft request' (duration: 500.820541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:08.525257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:08.022567Z","time spent":"502.435254ms","remote":"127.0.0.1:51868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T12:57:09.217408Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407071059032730,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T12:57:10.558188Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.326939508s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-08T12:57:10.560777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330495561s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T12:57:10.561161Z","caller":"traceutil/trace.go:171","msg":"trace[1376464048] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"2.031186592s","start":"2026-04-08T12:57:08.529952Z","end":"2026-04-08T12:57:10.561138Z","steps":["trace[1376464048] 'process raft request' (duration: 700.241713ms)","trace[1376464048] 'compare' (duration: 1.330351699s)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:57:10.561266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:08.529925Z","time spent":"2.031290803s","remote":"127.0.0.1:52080","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-08T12:57:10.56139Z","caller":"traceutil/trace.go:171","msg":"trace[1588723295] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"2.024084998s","start":"2026-04-08T12:57:08.537285Z","end":"2026-04-08T12:57:10.56137Z","steps":["trace[1588723295] 'process raft request' (duration: 2.023815714s)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:10.561432Z","caller":"traceutil/trace.go:171","msg":"trace[1978034516] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"2.026666435s","start":"2026-04-08T12:57:08.534722Z","end":"2026-04-08T12:57:10.561389Z","steps":["trace[1978034516] 'process raft request' (duration: 2.026174237s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:10.561502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:08.537262Z","time spent":"2.02418582s","remote":"127.0.0.1:51868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T12:57:10.562329Z","caller":"traceutil/trace.go:171","msg":"trace[1649389612] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.977749967s","start":"2026-04-08T12:57:08.584553Z","end":"2026-04-08T12:57:10.562303Z","steps":["trace[1649389612] 'process raft request' (duration: 1.976758742s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:10.565974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:08.584527Z","time spent":"1.981390531s","remote":"127.0.0.1:51868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T12:57:10.563072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:08.534685Z","time spent":"2.026868379s","remote":"127.0.0.1:51868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T12:57:10.572449Z","caller":"traceutil/trace.go:171","msg":"trace[22296752] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:564; }","duration":"1.855427711s","start":"2026-04-08T12:57:08.716967Z","end":"2026-04-08T12:57:10.572395Z","steps":["trace[22296752] 'read index received' (duration: 513.286983ms)","trace[22296752] 'applied index is now lower than readState.Index' (duration: 1.342139808s)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:57:10.572486Z","caller":"traceutil/trace.go:171","msg":"trace[423427273] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.980591254s","start":"2026-04-08T12:57:08.591872Z","end":"2026-04-08T12:57:10.572463Z","steps":["trace[423427273] 'process raft request' (duration: 1.977625839s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:10.572588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:08.591863Z","time spent":"1.980668685s","remote":"127.0.0.1:51868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T12:57:10.572941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.855976949s","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-08T12:57:10.572987Z","caller":"traceutil/trace.go:171","msg":"trace[1537491915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:546; }","duration":"1.85605776s","start":"2026-04-08T12:57:08.716917Z","end":"2026-04-08T12:57:10.572975Z","steps":["trace[1537491915] 'agreement among raft nodes before linearized reading' (duration: 1.855909427s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:10.573018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:08.716898Z","time spent":"1.856111351s","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T12:57:10.574545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.710052424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T12:57:10.574711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.191186701s","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-08T12:57:10.574758Z","caller":"traceutil/trace.go:171","msg":"trace[424937910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:546; }","duration":"1.191274493s","start":"2026-04-08T12:57:09.383472Z","end":"2026-04-08T12:57:10.574747Z","steps":["trace[424937910] 'agreement among raft nodes before linearized reading' (duration: 1.191185271s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:10.574801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:09.383451Z","time spent":"1.191341453s","remote":"127.0.0.1:52080","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":"info","ts":"2026-04-08T12:57:10.574582Z","caller":"traceutil/trace.go:171","msg":"trace[513843328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"1.710135395s","start":"2026-04-08T12:57:08.864437Z","end":"2026-04-08T12:57:10.574572Z","steps":["trace[513843328] 'agreement among raft nodes before linearized reading' (duration: 1.710078214s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:10.574913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:08.864411Z","time spent":"1.71047664s","remote":"127.0.0.1:51820","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-08T12:57:10.986816Z","caller":"traceutil/trace.go:171","msg":"trace[2001198447] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"211.156583ms","start":"2026-04-08T12:57:10.775632Z","end":"2026-04-08T12:57:10.986789Z","steps":["trace[2001198447] 'process raft request' (duration: 211.02394ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:10.986766Z","caller":"traceutil/trace.go:171","msg":"trace[656569671] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"215.869105ms","start":"2026-04-08T12:57:10.770867Z","end":"2026-04-08T12:57:10.986736Z","steps":["trace[656569671] 'process raft request' (duration: 193.479717ms)","trace[656569671] 'compare' (duration: 21.967381ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:57:10.98783Z","caller":"traceutil/trace.go:171","msg":"trace[1076829802] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"141.415599ms","start":"2026-04-08T12:57:10.846395Z","end":"2026-04-08T12:57:10.98781Z","steps":["trace[1076829802] 'process raft request' (duration: 141.268347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:11.560351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.276243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T12:57:11.560676Z","caller":"traceutil/trace.go:171","msg":"trace[1552433871] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"216.379838ms","start":"2026-04-08T12:57:11.344255Z","end":"2026-04-08T12:57:11.560635Z","steps":["trace[1552433871] 'process raft request' (duration: 77.737249ms)","trace[1552433871] 'compare' (duration: 138.125681ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:57:11.560969Z","caller":"traceutil/trace.go:171","msg":"trace[116465757] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"191.610615ms","start":"2026-04-08T12:57:11.369326Z","end":"2026-04-08T12:57:11.560936Z","steps":["trace[116465757] 'process raft request' (duration: 191.26081ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:11.561011Z","caller":"traceutil/trace.go:171","msg":"trace[1688277777] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"188.114382ms","start":"2026-04-08T12:57:11.372881Z","end":"2026-04-08T12:57:11.560995Z","steps":["trace[1688277777] 'process raft request' (duration: 187.916059ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:11.724575Z","caller":"traceutil/trace.go:171","msg":"trace[1395138359] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"130.329671ms","start":"2026-04-08T12:57:11.594212Z","end":"2026-04-08T12:57:11.724542Z","steps":["trace[1395138359] 'process raft request' (duration: 104.97068ms)","trace[1395138359] 'compare' (duration: 25.074477ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:57:12.004698Z","caller":"traceutil/trace.go:171","msg":"trace[1934953571] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"200.737259ms","start":"2026-04-08T12:57:11.803929Z","end":"2026-04-08T12:57:12.004667Z","steps":["trace[1934953571] 'process raft request' (duration: 125.099941ms)","trace[1934953571] 'compare' (duration: 75.019508ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:57:12.004778Z","caller":"traceutil/trace.go:171","msg":"trace[2051157260] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"189.432994ms","start":"2026-04-08T12:57:11.815316Z","end":"2026-04-08T12:57:12.004749Z","steps":["trace[2051157260] 'process raft request' (duration: 189.296252ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:12.01054Z","caller":"traceutil/trace.go:171","msg":"trace[842431538] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:605; }","duration":"150.652947ms","start":"2026-04-08T12:57:11.859837Z","end":"2026-04-08T12:57:12.01049Z","steps":["trace[842431538] 'read index received' (duration: 68.892823ms)","trace[842431538] 'applied index is now lower than readState.Index' (duration: 81.649392ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:57:12.011049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.189575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:57:12.011179Z","caller":"traceutil/trace.go:171","msg":"trace[644241684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"151.354198ms","start":"2026-04-08T12:57:11.859788Z","end":"2026-04-08T12:57:12.011142Z","steps":["trace[644241684] 'agreement among raft nodes before linearized reading' (duration: 150.786569ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:14.460762Z","caller":"traceutil/trace.go:171","msg":"trace[1005134877] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"578.451874ms","start":"2026-04-08T12:57:13.882253Z","end":"2026-04-08T12:57:14.460705Z","steps":["trace[1005134877] 'process raft request' (duration: 578.288051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:14.460893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:13.882236Z","time spent":"578.595586ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T12:57:15.039613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.509289ms","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-08T12:57:15.039706Z","caller":"traceutil/trace.go:171","msg":"trace[2047501964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:597; }","duration":"399.657051ms","start":"2026-04-08T12:57:14.640029Z","end":"2026-04-08T12:57:15.039686Z","steps":["trace[2047501964] 'range keys from in-memory index tree' (duration: 399.338376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:15.039749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:14.640008Z","time spent":"399.732222ms","remote":"127.0.0.1:52080","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-08T12:57:15.040079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.138665ms","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-08T12:57:15.040116Z","caller":"traceutil/trace.go:171","msg":"trace[1765954322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"461.209596ms","start":"2026-04-08T12:57:14.578896Z","end":"2026-04-08T12:57:15.040106Z","steps":["trace[1765954322] 'range keys from in-memory index tree' (duration: 461.022373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:15.040145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:14.578876Z","time spent":"461.260137ms","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T12:57:15.040546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.09998ms","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-08T12:57:15.040693Z","caller":"traceutil/trace.go:171","msg":"trace[797243310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"362.296653ms","start":"2026-04-08T12:57:14.678349Z","end":"2026-04-08T12:57:15.040646Z","steps":["trace[797243310] 'range keys from in-memory index tree' (duration: 361.881736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:15.04079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:14.678334Z","time spent":"362.441035ms","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T12:57:15.040976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.49031ms","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-08T12:57:15.041024Z","caller":"traceutil/trace.go:171","msg":"trace[1503546124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"399.562871ms","start":"2026-04-08T12:57:14.64145Z","end":"2026-04-08T12:57:15.041013Z","steps":["trace[1503546124] 'range keys from in-memory index tree' (duration: 399.356248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:15.04106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:14.641437Z","time spent":"399.615942ms","remote":"127.0.0.1:52080","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":"info","ts":"2026-04-08T12:57:15.042244Z","caller":"traceutil/trace.go:171","msg":"trace[2038774091] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"178.972942ms","start":"2026-04-08T12:57:14.863249Z","end":"2026-04-08T12:57:15.042222Z","steps":["trace[2038774091] 'read index received' (duration: 178.2595ms)","trace[2038774091] 'applied index is now lower than readState.Index' (duration: 712.512µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:57:15.042557Z","caller":"traceutil/trace.go:171","msg":"trace[286556334] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"181.267529ms","start":"2026-04-08T12:57:14.861266Z","end":"2026-04-08T12:57:15.042534Z","steps":["trace[286556334] 'process raft request' (duration: 180.561307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:15.04356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.295515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:57:15.043777Z","caller":"traceutil/trace.go:171","msg":"trace[1192749643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"180.422937ms","start":"2026-04-08T12:57:14.863178Z","end":"2026-04-08T12:57:15.043601Z","steps":["trace[1192749643] 'agreement among raft nodes before linearized reading' (duration: 179.135055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:23.363526Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407071059032989,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T12:57:23.863973Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407071059032989,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T12:57:24.364977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407071059032989,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T12:57:24.86336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00063019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-08T12:57:24.863445Z","caller":"traceutil/trace.go:171","msg":"trace[1940674530] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000760442s","start":"2026-04-08T12:57:22.862664Z","end":"2026-04-08T12:57:24.863424Z","steps":["trace[1940674530] 'agreement among raft nodes before linearized reading' (duration: 2.00065273s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:24.863493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:22.86265Z","time spent":"2.000833074s","remote":"127.0.0.1:51820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T12:57:24.865879Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407071059032989,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T12:57:25.305178Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.49122126s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T12:57:25.305587Z","caller":"traceutil/trace.go:171","msg":"trace[1086530876] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"2.442862925s","start":"2026-04-08T12:57:22.862699Z","end":"2026-04-08T12:57:25.305562Z","steps":["trace[1086530876] 'read index received' (duration: 2.44261548s)","trace[1086530876] 'applied index is now lower than readState.Index' (duration: 246.355µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:57:25.305647Z","caller":"traceutil/trace.go:171","msg":"trace[1949512131] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"2.49178415s","start":"2026-04-08T12:57:22.813846Z","end":"2026-04-08T12:57:25.30563Z","steps":["trace[1949512131] 'process raft request' (duration: 2.491546996s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:25.305805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.179741598s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T12:57:25.305842Z","caller":"traceutil/trace.go:171","msg":"trace[1680891738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"2.179801309s","start":"2026-04-08T12:57:23.126032Z","end":"2026-04-08T12:57:25.305834Z","steps":["trace[1680891738] 'agreement among raft nodes before linearized reading' (duration: 2.179721338s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:25.305865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:23.12602Z","time spent":"2.17984022s","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T12:57:25.305881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.20118181s","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-08T12:57:25.305938Z","caller":"traceutil/trace.go:171","msg":"trace[223477127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"2.201281322s","start":"2026-04-08T12:57:23.104642Z","end":"2026-04-08T12:57:25.305923Z","steps":["trace[223477127] 'agreement among raft nodes before linearized reading' (duration: 2.201078608s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:25.30596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.055041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:57:25.305979Z","caller":"traceutil/trace.go:171","msg":"trace[1537558242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"442.108122ms","start":"2026-04-08T12:57:24.863866Z","end":"2026-04-08T12:57:25.305974Z","steps":["trace[1537558242] 'agreement among raft nodes before linearized reading' (duration: 442.080071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:25.305993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:24.86385Z","time spent":"442.140373ms","remote":"127.0.0.1:51818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T12:57:25.305989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:23.104621Z","time spent":"2.201354824s","remote":"127.0.0.1:52080","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-08T12:57:25.306046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.184221732s","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-08T12:57:25.306095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.184375845s","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-08T12:57:25.306477Z","caller":"traceutil/trace.go:171","msg":"trace[1507248140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"2.184781213s","start":"2026-04-08T12:57:23.121673Z","end":"2026-04-08T12:57:25.306454Z","steps":["trace[1507248140] 'agreement among raft nodes before linearized reading' (duration: 2.184170721s)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:25.306576Z","caller":"traceutil/trace.go:171","msg":"trace[298773951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"2.184903495s","start":"2026-04-08T12:57:23.121662Z","end":"2026-04-08T12:57:25.306566Z","steps":["trace[298773951] 'agreement among raft nodes before linearized reading' (duration: 2.184297233s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:25.306604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:23.121642Z","time spent":"2.184953497s","remote":"127.0.0.1:52080","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-08T12:57:25.306617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:23.121655Z","time spent":"2.184946736s","remote":"127.0.0.1:52080","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-08T12:57:25.306188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:22.813826Z","time spent":"2.491867781s","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T12:57:25.496772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.763158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T12:57:25.496966Z","caller":"traceutil/trace.go:171","msg":"trace[734130079] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"186.550169ms","start":"2026-04-08T12:57:25.310379Z","end":"2026-04-08T12:57:25.496929Z","steps":["trace[734130079] 'process raft request' (duration: 83.551377ms)","trace[734130079] 'compare' (duration: 102.619565ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:57:25.497047Z","caller":"traceutil/trace.go:171","msg":"trace[1416285552] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"183.728635ms","start":"2026-04-08T12:57:25.313302Z","end":"2026-04-08T12:57:25.49703Z","steps":["trace[1416285552] 'process raft request' (duration: 183.659323ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:25.497042Z","caller":"traceutil/trace.go:171","msg":"trace[2060317874] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"186.147251ms","start":"2026-04-08T12:57:25.310864Z","end":"2026-04-08T12:57:25.497011Z","steps":["trace[2060317874] 'process raft request' (duration: 186.008039ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:25.497099Z","caller":"traceutil/trace.go:171","msg":"trace[2018544587] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"186.0542ms","start":"2026-04-08T12:57:25.311022Z","end":"2026-04-08T12:57:25.497076Z","steps":["trace[2018544587] 'process raft request' (duration: 185.899257ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:25.497192Z","caller":"traceutil/trace.go:171","msg":"trace[1430300906] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"182.311158ms","start":"2026-04-08T12:57:25.314869Z","end":"2026-04-08T12:57:25.49718Z","steps":["trace[1430300906] 'process raft request' (duration: 182.125694ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:25.497239Z","caller":"traceutil/trace.go:171","msg":"trace[1564655241] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:666; }","duration":"183.377088ms","start":"2026-04-08T12:57:25.313813Z","end":"2026-04-08T12:57:25.49719Z","steps":["trace[1564655241] 'read index received' (duration: 80.140711ms)","trace[1564655241] 'applied index is now lower than readState.Index' (duration: 103.235047ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:57:25.497354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.52421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:57:25.497424Z","caller":"traceutil/trace.go:171","msg":"trace[142999078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"183.603472ms","start":"2026-04-08T12:57:25.313805Z","end":"2026-04-08T12:57:25.497408Z","steps":["trace[142999078] 'agreement among raft nodes before linearized reading' (duration: 183.49367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:26.270763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.202693ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-08T12:57:26.49008Z","caller":"traceutil/trace.go:171","msg":"trace[1318105494] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"167.572504ms","start":"2026-04-08T12:57:26.322478Z","end":"2026-04-08T12:57:26.49005Z","steps":["trace[1318105494] 'process raft request' (duration: 167.39302ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:26.603365Z","caller":"traceutil/trace.go:171","msg":"trace[627494832] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"267.113823ms","start":"2026-04-08T12:57:26.336229Z","end":"2026-04-08T12:57:26.603342Z","steps":["trace[627494832] 'process raft request' (duration: 243.690228ms)","trace[627494832] 'compare' (duration: 23.283053ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:57:29.106602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.655726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:57:29.106708Z","caller":"traceutil/trace.go:171","msg":"trace[330577214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:662; }","duration":"244.807259ms","start":"2026-04-08T12:57:28.861881Z","end":"2026-04-08T12:57:29.106688Z","steps":["trace[330577214] 'range keys from in-memory index tree' (duration: 244.532154ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:31.804015Z","caller":"traceutil/trace.go:171","msg":"trace[1629116883] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"207.001261ms","start":"2026-04-08T12:57:31.596989Z","end":"2026-04-08T12:57:31.803991Z","steps":["trace[1629116883] 'process raft request' (duration: 206.836318ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:31.810738Z","caller":"traceutil/trace.go:171","msg":"trace[714561045] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"211.615866ms","start":"2026-04-08T12:57:31.599098Z","end":"2026-04-08T12:57:31.810714Z","steps":["trace[714561045] 'process raft request' (duration: 211.541435ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:31.811119Z","caller":"traceutil/trace.go:171","msg":"trace[396312633] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"213.665557ms","start":"2026-04-08T12:57:31.597441Z","end":"2026-04-08T12:57:31.811107Z","steps":["trace[396312633] 'process raft request' (duration: 213.072705ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:31.811521Z","caller":"traceutil/trace.go:171","msg":"trace[1705118885] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"211.730878ms","start":"2026-04-08T12:57:31.599746Z","end":"2026-04-08T12:57:31.811476Z","steps":["trace[1705118885] 'process raft request' (duration: 210.937382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:43.93692Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407071059033322,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T12:57:44.437514Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407071059033322,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T12:57:44.793526Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.558638594s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T12:57:44.793953Z","caller":"traceutil/trace.go:171","msg":"trace[1352499874] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"1.559127346s","start":"2026-04-08T12:57:43.2348Z","end":"2026-04-08T12:57:44.793927Z","steps":["trace[1352499874] 'process raft request' (duration: 1.558977933s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:44.794109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:43.234784Z","time spent":"1.559243628s","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T12:57:44.802854Z","caller":"traceutil/trace.go:171","msg":"trace[1482597825] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:835; }","duration":"1.36664186s","start":"2026-04-08T12:57:43.436189Z","end":"2026-04-08T12:57:44.802831Z","steps":["trace[1482597825] 'read index received' (duration: 1.357686332s)","trace[1482597825] 'applied index is now lower than readState.Index' (duration: 8.954198ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:57:44.80308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.366889695s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xmhzq\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-08T12:57:44.80313Z","caller":"traceutil/trace.go:171","msg":"trace[686780994] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xmhzq; range_end:; response_count:1; response_revision:805; }","duration":"1.366983358s","start":"2026-04-08T12:57:43.436137Z","end":"2026-04-08T12:57:44.80312Z","steps":["trace[686780994] 'agreement among raft nodes before linearized reading' (duration: 1.366813424s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:44.803162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:43.4361Z","time spent":"1.367056229s","remote":"127.0.0.1:52006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xmhzq\" "} {"level":"info","ts":"2026-04-08T12:57:44.802908Z","caller":"traceutil/trace.go:171","msg":"trace[884535999] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"909.312347ms","start":"2026-04-08T12:57:43.893575Z","end":"2026-04-08T12:57:44.802887Z","steps":["trace[884535999] 'process raft request' (duration: 909.140913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:44.803441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:43.893563Z","time spent":"909.752028ms","remote":"127.0.0.1:51992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4904,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T12:57:44.803581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.299543ms","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-08T12:57:44.803618Z","caller":"traceutil/trace.go:171","msg":"trace[605239469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"924.370555ms","start":"2026-04-08T12:57:43.879238Z","end":"2026-04-08T12:57:44.803609Z","steps":["trace[605239469] 'agreement among raft nodes before linearized reading' (duration: 924.275333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:44.803639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:43.87923Z","time spent":"924.403965ms","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T12:57:44.803667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.947078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-08T12:57:44.803661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.449136ms","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-08T12:57:44.803708Z","caller":"traceutil/trace.go:171","msg":"trace[945793912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"925.02403ms","start":"2026-04-08T12:57:43.878677Z","end":"2026-04-08T12:57:44.803701Z","steps":["trace[945793912] 'agreement among raft nodes before linearized reading' (duration: 924.837836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:44.803661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.016599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-08T12:57:44.803725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:43.878657Z","time spent":"925.0634ms","remote":"127.0.0.1:52080","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-08T12:57:44.803735Z","caller":"traceutil/trace.go:171","msg":"trace[1107507002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:805; }","duration":"947.564869ms","start":"2026-04-08T12:57:43.856152Z","end":"2026-04-08T12:57:44.803717Z","steps":["trace[1107507002] 'agreement among raft nodes before linearized reading' (duration: 947.368235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:44.80379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:43.856133Z","time spent":"947.647781ms","remote":"127.0.0.1:52080","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":"info","ts":"2026-04-08T12:57:44.803747Z","caller":"traceutil/trace.go:171","msg":"trace[545918552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:805; }","duration":"925.150823ms","start":"2026-04-08T12:57:43.878585Z","end":"2026-04-08T12:57:44.803736Z","steps":["trace[545918552] 'agreement among raft nodes before linearized reading' (duration: 924.917797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:44.803858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.794076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T12:57:44.803882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:43.878562Z","time spent":"925.311326ms","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T12:57:44.803909Z","caller":"traceutil/trace.go:171","msg":"trace[1454143144] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:805; }","duration":"993.901077ms","start":"2026-04-08T12:57:43.809992Z","end":"2026-04-08T12:57:44.803893Z","steps":["trace[1454143144] 'agreement among raft nodes before linearized reading' (duration: 993.775794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:44.803952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:43.809974Z","time spent":"993.967919ms","remote":"127.0.0.1:52204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-04-08T12:57:44.804225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324495699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T12:57:44.804303Z","caller":"traceutil/trace.go:171","msg":"trace[761780084] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:805; }","duration":"1.324613301s","start":"2026-04-08T12:57:43.479667Z","end":"2026-04-08T12:57:44.80428Z","steps":["trace[761780084] 'agreement among raft nodes before linearized reading' (duration: 1.323817773s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:44.804356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:43.479648Z","time spent":"1.324696334s","remote":"127.0.0.1:51868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-04-08T12:57:57.101917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.567512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T12:57:57.102066Z","caller":"traceutil/trace.go:171","msg":"trace[789921555] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"147.879223ms","start":"2026-04-08T12:57:56.95413Z","end":"2026-04-08T12:57:57.102009Z","steps":["trace[789921555] 'process raft request' (duration: 31.142566ms)","trace[789921555] 'compare' (duration: 116.438579ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:57:57.10648Z","caller":"traceutil/trace.go:171","msg":"trace[957656027] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:988; }","duration":"150.909469ms","start":"2026-04-08T12:57:56.955553Z","end":"2026-04-08T12:57:57.106463Z","steps":["trace[957656027] 'read index received' (duration: 29.728589ms)","trace[957656027] 'applied index is now lower than readState.Index' (duration: 121.18021ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:57:57.106661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.089673ms","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-08T12:57:57.106685Z","caller":"traceutil/trace.go:171","msg":"trace[1450366875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:955; }","duration":"151.141155ms","start":"2026-04-08T12:57:56.955536Z","end":"2026-04-08T12:57:57.106677Z","steps":["trace[1450366875] 'agreement among raft nodes before linearized reading' (duration: 151.003982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:57.10675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.551328ms","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-08T12:57:57.106826Z","caller":"traceutil/trace.go:171","msg":"trace[6888125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:955; }","duration":"140.666971ms","start":"2026-04-08T12:57:56.966145Z","end":"2026-04-08T12:57:57.106812Z","steps":["trace[6888125] 'agreement among raft nodes before linearized reading' (duration: 140.453575ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:57.106521Z","caller":"traceutil/trace.go:171","msg":"trace[1081799439] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"151.094734ms","start":"2026-04-08T12:57:56.955405Z","end":"2026-04-08T12:57:57.1065Z","steps":["trace[1081799439] 'process raft request' (duration: 150.979691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:57.106912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.210085ms","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-08T12:57:57.106985Z","caller":"traceutil/trace.go:171","msg":"trace[315282153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"139.325378ms","start":"2026-04-08T12:57:56.96764Z","end":"2026-04-08T12:57:57.106966Z","steps":["trace[315282153] 'agreement among raft nodes before linearized reading' (duration: 139.111372ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:57.291711Z","caller":"traceutil/trace.go:171","msg":"trace[691144502] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"177.814639ms","start":"2026-04-08T12:57:57.11388Z","end":"2026-04-08T12:57:57.291695Z","steps":["trace[691144502] 'process raft request' (duration: 177.784378ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:57.291902Z","caller":"traceutil/trace.go:171","msg":"trace[1674180977] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"179.974083ms","start":"2026-04-08T12:57:57.111901Z","end":"2026-04-08T12:57:57.291875Z","steps":["trace[1674180977] 'process raft request' (duration: 166.550123ms)","trace[1674180977] 'compare' (duration: 13.123013ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:57:57.746812Z","caller":"traceutil/trace.go:171","msg":"trace[1140704417] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"114.735539ms","start":"2026-04-08T12:57:57.632055Z","end":"2026-04-08T12:57:57.746791Z","steps":["trace[1140704417] 'process raft request' (duration: 114.591936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:59.364655Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407071059033610,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T12:57:59.545245Z","caller":"traceutil/trace.go:171","msg":"trace[152325008] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"681.313532ms","start":"2026-04-08T12:57:58.863907Z","end":"2026-04-08T12:57:59.545221Z","steps":["trace[152325008] 'read index received' (duration: 681.096496ms)","trace[152325008] 'applied index is now lower than readState.Index' (duration: 216.226µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:57:59.545397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.468526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:57:59.545388Z","caller":"traceutil/trace.go:171","msg":"trace[1411399274] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"722.537634ms","start":"2026-04-08T12:57:58.82281Z","end":"2026-04-08T12:57:59.545348Z","steps":["trace[1411399274] 'process raft request' (duration: 722.265357ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:59.545445Z","caller":"traceutil/trace.go:171","msg":"trace[108969917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"681.569388ms","start":"2026-04-08T12:57:58.863866Z","end":"2026-04-08T12:57:59.545435Z","steps":["trace[108969917] 'agreement among raft nodes before linearized reading' (duration: 681.463656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:59.545477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:58.863846Z","time spent":"681.62357ms","remote":"127.0.0.1:51820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T12:57:59.545536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.997332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-zns97\" ","response":"range_response_count:1 size:5673"} {"level":"info","ts":"2026-04-08T12:57:59.545578Z","caller":"traceutil/trace.go:171","msg":"trace[150185725] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-zns97; range_end:; response_count:1; response_revision:960; }","duration":"646.073774ms","start":"2026-04-08T12:57:58.899491Z","end":"2026-04-08T12:57:59.545565Z","steps":["trace[150185725] 'agreement among raft nodes before linearized reading' (duration: 645.92616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:59.545579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:58.822791Z","time spent":"722.679427ms","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T12:57:59.545603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:58.899472Z","time spent":"646.124374ms","remote":"127.0.0.1:52006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5697,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-zns97\" "} {"level":"warn","ts":"2026-04-08T12:57:59.545828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.209123ms","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-08T12:57:59.545861Z","caller":"traceutil/trace.go:171","msg":"trace[1377884279] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:960; }","duration":"439.274365ms","start":"2026-04-08T12:57:59.106575Z","end":"2026-04-08T12:57:59.54585Z","steps":["trace[1377884279] 'agreement among raft nodes before linearized reading' (duration: 439.150942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:59.545892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:59.106524Z","time spent":"439.360658ms","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T12:57:59.546049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.733041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-08T12:57:59.546051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.105399ms","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-08T12:57:59.546082Z","caller":"traceutil/trace.go:171","msg":"trace[1296838184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:960; }","duration":"250.786361ms","start":"2026-04-08T12:57:59.295287Z","end":"2026-04-08T12:57:59.546073Z","steps":["trace[1296838184] 'agreement among raft nodes before linearized reading' (duration: 250.640048ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:59.546117Z","caller":"traceutil/trace.go:171","msg":"trace[561417986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:960; }","duration":"625.195292ms","start":"2026-04-08T12:57:58.920904Z","end":"2026-04-08T12:57:59.5461Z","steps":["trace[561417986] 'agreement among raft nodes before linearized reading' (duration: 624.943786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:59.546078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.000962ms","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-08T12:57:59.546166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:58.920894Z","time spent":"625.256673ms","remote":"127.0.0.1:52080","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-08T12:57:59.546201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.419228ms","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-08T12:57:59.546232Z","caller":"traceutil/trace.go:171","msg":"trace[328710843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:960; }","duration":"251.481469ms","start":"2026-04-08T12:57:59.294742Z","end":"2026-04-08T12:57:59.546223Z","steps":["trace[328710843] 'agreement among raft nodes before linearized reading' (duration: 251.402687ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:59.54625Z","caller":"traceutil/trace.go:171","msg":"trace[114583343] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:960; }","duration":"351.228317ms","start":"2026-04-08T12:57:59.194996Z","end":"2026-04-08T12:57:59.546224Z","steps":["trace[114583343] 'agreement among raft nodes before linearized reading' (duration: 350.658382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:59.5464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:59.194968Z","time spent":"351.378921ms","remote":"127.0.0.1:52080","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":"info","ts":"2026-04-08T12:57:59.662402Z","caller":"traceutil/trace.go:171","msg":"trace[1317595089] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"111.268904ms","start":"2026-04-08T12:57:59.551109Z","end":"2026-04-08T12:57:59.662378Z","steps":["trace[1317595089] 'process raft request' (duration: 98.859836ms)","trace[1317595089] 'compare' (duration: 12.219353ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:57:59.662813Z","caller":"traceutil/trace.go:171","msg":"trace[2129065300] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"110.805921ms","start":"2026-04-08T12:57:59.551982Z","end":"2026-04-08T12:57:59.662788Z","steps":["trace[2129065300] 'process raft request' (duration: 110.334729ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:59.66283Z","caller":"traceutil/trace.go:171","msg":"trace[1884253059] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"110.599826ms","start":"2026-04-08T12:57:59.552218Z","end":"2026-04-08T12:57:59.662818Z","steps":["trace[1884253059] 'process raft request' (duration: 110.490013ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:59.663009Z","caller":"traceutil/trace.go:171","msg":"trace[563731149] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"110.77469ms","start":"2026-04-08T12:57:59.552222Z","end":"2026-04-08T12:57:59.662996Z","steps":["trace[563731149] 'process raft request' (duration: 110.532904ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:59.663134Z","caller":"traceutil/trace.go:171","msg":"trace[1285255479] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"108.957304ms","start":"2026-04-08T12:57:59.554168Z","end":"2026-04-08T12:57:59.663125Z","steps":["trace[1285255479] 'process raft request' (duration: 108.619915ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:59.824735Z","caller":"traceutil/trace.go:171","msg":"trace[1407654717] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"154.581374ms","start":"2026-04-08T12:57:59.670135Z","end":"2026-04-08T12:57:59.824717Z","steps":["trace[1407654717] 'process raft request' (duration: 134.627312ms)","trace[1407654717] 'compare' (duration: 19.811258ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:57:59.824904Z","caller":"traceutil/trace.go:171","msg":"trace[1361348271] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"154.174253ms","start":"2026-04-08T12:57:59.670695Z","end":"2026-04-08T12:57:59.824869Z","steps":["trace[1361348271] 'process raft request' (duration: 153.980359ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:59.854325Z","caller":"traceutil/trace.go:171","msg":"trace[131641068] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"101.395341ms","start":"2026-04-08T12:57:59.752905Z","end":"2026-04-08T12:57:59.8543Z","steps":["trace[131641068] 'process raft request' (duration: 101.269708ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:58:00.00931Z","caller":"traceutil/trace.go:171","msg":"trace[2036182646] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"176.767184ms","start":"2026-04-08T12:57:59.832516Z","end":"2026-04-08T12:58:00.009284Z","steps":["trace[2036182646] 'process raft request' (duration: 175.621365ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:58:00.375907Z","caller":"traceutil/trace.go:171","msg":"trace[1372092808] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"133.791251ms","start":"2026-04-08T12:58:00.2421Z","end":"2026-04-08T12:58:00.375891Z","steps":["trace[1372092808] 'read index received' (duration: 133.649377ms)","trace[1372092808] 'applied index is now lower than readState.Index' (duration: 141.344µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:58:00.375963Z","caller":"traceutil/trace.go:171","msg":"trace[1185342995] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"273.859522ms","start":"2026-04-08T12:58:00.102074Z","end":"2026-04-08T12:58:00.375933Z","steps":["trace[1185342995] 'process raft request' (duration: 273.714158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:58:00.376001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.895933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:58:00.376027Z","caller":"traceutil/trace.go:171","msg":"trace[2120249673] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:973; }","duration":"133.996346ms","start":"2026-04-08T12:58:00.24202Z","end":"2026-04-08T12:58:00.376016Z","steps":["trace[2120249673] 'agreement among raft nodes before linearized reading' (duration: 133.934374ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:58:00.624524Z","caller":"traceutil/trace.go:171","msg":"trace[533317926] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"102.283978ms","start":"2026-04-08T12:58:00.522212Z","end":"2026-04-08T12:58:00.624496Z","steps":["trace[533317926] 'process raft request' (duration: 101.754184ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:58:01.773716Z","caller":"traceutil/trace.go:171","msg":"trace[1805520470] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"102.59608ms","start":"2026-04-08T12:58:01.671102Z","end":"2026-04-08T12:58:01.773698Z","steps":["trace[1805520470] 'process raft request' (duration: 101.676897ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:58:01.773924Z","caller":"traceutil/trace.go:171","msg":"trace[1063048992] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"102.56671ms","start":"2026-04-08T12:58:01.67134Z","end":"2026-04-08T12:58:01.773907Z","steps":["trace[1063048992] 'process raft request' (duration: 102.330814ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:58:01.773952Z","caller":"traceutil/trace.go:171","msg":"trace[222147729] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"102.047486ms","start":"2026-04-08T12:58:01.671898Z","end":"2026-04-08T12:58:01.773945Z","steps":["trace[222147729] 'process raft request' (duration: 101.946263ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:58:19.963527Z","caller":"traceutil/trace.go:171","msg":"trace[1742162973] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1088; }","duration":"100.984757ms","start":"2026-04-08T12:58:19.862519Z","end":"2026-04-08T12:58:19.963504Z","steps":["trace[1742162973] 'read index received' (duration: 100.653887ms)","trace[1742162973] 'applied index is now lower than readState.Index' (duration: 328.57µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:58:19.963788Z","caller":"traceutil/trace.go:171","msg":"trace[864452726] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"101.973533ms","start":"2026-04-08T12:58:19.861739Z","end":"2026-04-08T12:58:19.963713Z","steps":["trace[864452726] 'process raft request' (duration: 101.49127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:58:19.964056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.515181ms","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-08T12:58:19.964444Z","caller":"traceutil/trace.go:171","msg":"trace[1568378863] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1049; }","duration":"101.753948ms","start":"2026-04-08T12:58:19.862502Z","end":"2026-04-08T12:58:19.964256Z","steps":["trace[1568378863] 'agreement among raft nodes before linearized reading' (duration: 101.424118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:58:20.005968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.115294ms","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-08T12:58:20.006057Z","caller":"traceutil/trace.go:171","msg":"trace[704045115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1051; }","duration":"141.217257ms","start":"2026-04-08T12:58:19.864817Z","end":"2026-04-08T12:58:20.006034Z","steps":["trace[704045115] 'agreement among raft nodes before linearized reading' (duration: 140.908878ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:58:50.160041Z","caller":"traceutil/trace.go:171","msg":"trace[852321523] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"137.715579ms","start":"2026-04-08T12:58:50.022297Z","end":"2026-04-08T12:58:50.160013Z","steps":["trace[852321523] 'process raft request' (duration: 106.793741ms)","trace[852321523] 'compare' (duration: 30.754203ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:58:58.369579Z","caller":"traceutil/trace.go:171","msg":"trace[1601456908] linearizableReadLoop","detail":"{readStateIndex:1223; appliedIndex:1222; }","duration":"103.466469ms","start":"2026-04-08T12:58:58.266084Z","end":"2026-04-08T12:58:58.369551Z","steps":["trace[1601456908] 'read index received' (duration: 48.245871ms)","trace[1601456908] 'applied index is now lower than readState.Index' (duration: 55.219728ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:58:58.369622Z","caller":"traceutil/trace.go:171","msg":"trace[645541123] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"121.589295ms","start":"2026-04-08T12:58:58.248011Z","end":"2026-04-08T12:58:58.3696Z","steps":["trace[645541123] 'process raft request' (duration: 66.376056ms)","trace[645541123] 'compare' (duration: 55.032183ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:58:58.370003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.901813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-08T12:58:58.370039Z","caller":"traceutil/trace.go:171","msg":"trace[715232625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1175; }","duration":"103.989946ms","start":"2026-04-08T12:58:58.26604Z","end":"2026-04-08T12:58:58.37003Z","steps":["trace[715232625] 'agreement among raft nodes before linearized reading' (duration: 103.632655ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:58:58.558735Z","caller":"traceutil/trace.go:171","msg":"trace[261254873] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"185.021007ms","start":"2026-04-08T12:58:58.373653Z","end":"2026-04-08T12:58:58.558674Z","steps":["trace[261254873] 'process raft request' (duration: 139.962235ms)","trace[261254873] 'compare' (duration: 44.332639ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:58:58.558763Z","caller":"traceutil/trace.go:171","msg":"trace[805104913] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"184.924614ms","start":"2026-04-08T12:58:58.373818Z","end":"2026-04-08T12:58:58.558743Z","steps":["trace[805104913] 'process raft request' (duration: 184.527212ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:06.255167Z","caller":"traceutil/trace.go:171","msg":"trace[1936332607] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"116.846664ms","start":"2026-04-08T12:59:06.1383Z","end":"2026-04-08T12:59:06.255147Z","steps":["trace[1936332607] 'process raft request' (duration: 116.729191ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:06.981291Z","caller":"traceutil/trace.go:171","msg":"trace[1631407229] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"119.583152ms","start":"2026-04-08T12:59:06.861684Z","end":"2026-04-08T12:59:06.981267Z","steps":["trace[1631407229] 'process raft request' (duration: 119.448818ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:12.49074Z","caller":"traceutil/trace.go:171","msg":"trace[662319701] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"214.754043ms","start":"2026-04-08T12:59:12.275971Z","end":"2026-04-08T12:59:12.490726Z","steps":["trace[662319701] 'process raft request' (duration: 214.574908ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:12.490728Z","caller":"traceutil/trace.go:171","msg":"trace[39780198] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1270; }","duration":"212.743231ms","start":"2026-04-08T12:59:12.277959Z","end":"2026-04-08T12:59:12.490702Z","steps":["trace[39780198] 'read index received' (duration: 212.510383ms)","trace[39780198] 'applied index is now lower than readState.Index' (duration: 232.038µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:59:12.490885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.893895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T12:59:12.490916Z","caller":"traceutil/trace.go:171","msg":"trace[90747352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1220; }","duration":"212.991278ms","start":"2026-04-08T12:59:12.277918Z","end":"2026-04-08T12:59:12.490909Z","steps":["trace[90747352] 'agreement among raft nodes before linearized reading' (duration: 212.852114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:12.715593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.862824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T12:59:12.715703Z","caller":"traceutil/trace.go:171","msg":"trace[308205915] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"221.303043ms","start":"2026-04-08T12:59:12.494381Z","end":"2026-04-08T12:59:12.715684Z","steps":["trace[308205915] 'process raft request' (duration: 52.272153ms)","trace[308205915] 'compare' (duration: 168.762131ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:59:12.722583Z","caller":"traceutil/trace.go:171","msg":"trace[1109450186] linearizableReadLoop","detail":"{readStateIndex:1273; appliedIndex:1272; }","duration":"106.323713ms","start":"2026-04-08T12:59:12.616237Z","end":"2026-04-08T12:59:12.72256Z","steps":["trace[1109450186] 'read index received' (duration: 106.164338ms)","trace[1109450186] 'applied index is now lower than readState.Index' (duration: 158.525µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:59:12.722845Z","caller":"traceutil/trace.go:171","msg":"trace[1079000216] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"226.54581ms","start":"2026-04-08T12:59:12.496285Z","end":"2026-04-08T12:59:12.722831Z","steps":["trace[1079000216] 'process raft request' (duration: 226.172388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:12.723145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.888511ms","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-08T12:59:12.723184Z","caller":"traceutil/trace.go:171","msg":"trace[1925513956] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1222; }","duration":"106.969154ms","start":"2026-04-08T12:59:12.616206Z","end":"2026-04-08T12:59:12.723175Z","steps":["trace[1925513956] 'agreement among raft nodes before linearized reading' (duration: 106.822459ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:27.489088Z","caller":"traceutil/trace.go:171","msg":"trace[132683482] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"110.340367ms","start":"2026-04-08T12:59:27.378713Z","end":"2026-04-08T12:59:27.489054Z","steps":["trace[132683482] 'process raft request' (duration: 110.170302ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:27.490089Z","caller":"traceutil/trace.go:171","msg":"trace[67097537] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"110.351519ms","start":"2026-04-08T12:59:27.37971Z","end":"2026-04-08T12:59:27.490061Z","steps":["trace[67097537] 'process raft request' (duration: 110.233685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:29.246047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.280201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T12:59:29.246367Z","caller":"traceutil/trace.go:171","msg":"trace[1610610257] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"276.730541ms","start":"2026-04-08T12:59:28.969579Z","end":"2026-04-08T12:59:29.24631Z","steps":["trace[1610610257] 'process raft request' (duration: 147.113858ms)","trace[1610610257] 'compare' (duration: 128.92312ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:59:29.246477Z","caller":"traceutil/trace.go:171","msg":"trace[835014291] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1359; }","duration":"276.140002ms","start":"2026-04-08T12:59:28.970303Z","end":"2026-04-08T12:59:29.246443Z","steps":["trace[835014291] 'read index received' (duration: 18.655125ms)","trace[835014291] 'applied index is now lower than readState.Index' (duration: 257.483857ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:59:29.246569Z","caller":"traceutil/trace.go:171","msg":"trace[892436175] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"274.664504ms","start":"2026-04-08T12:59:28.971895Z","end":"2026-04-08T12:59:29.246559Z","steps":["trace[892436175] 'process raft request' (duration: 274.436737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:29.246653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.344379ms","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-08T12:59:29.24668Z","caller":"traceutil/trace.go:171","msg":"trace[1349739840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1306; }","duration":"276.391051ms","start":"2026-04-08T12:59:28.97028Z","end":"2026-04-08T12:59:29.246671Z","steps":["trace[1349739840] 'agreement among raft nodes before linearized reading' (duration: 276.298678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:29.24677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.08479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:59:29.246821Z","caller":"traceutil/trace.go:171","msg":"trace[514671844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1306; }","duration":"123.212294ms","start":"2026-04-08T12:59:29.123598Z","end":"2026-04-08T12:59:29.24681Z","steps":["trace[514671844] 'agreement among raft nodes before linearized reading' (duration: 123.120571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:29.246817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.382953ms","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-08T12:59:29.246913Z","caller":"traceutil/trace.go:171","msg":"trace[1757315804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1306; }","duration":"273.518038ms","start":"2026-04-08T12:59:28.973369Z","end":"2026-04-08T12:59:29.246887Z","steps":["trace[1757315804] 'agreement among raft nodes before linearized reading' (duration: 273.234798ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:29.448826Z","caller":"traceutil/trace.go:171","msg":"trace[280563482] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"197.978834ms","start":"2026-04-08T12:59:29.25082Z","end":"2026-04-08T12:59:29.448798Z","steps":["trace[280563482] 'process raft request' (duration: 173.321453ms)","trace[280563482] 'compare' (duration: 24.534957ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:59:29.634402Z","caller":"traceutil/trace.go:171","msg":"trace[1472476655] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"383.232374ms","start":"2026-04-08T12:59:29.251133Z","end":"2026-04-08T12:59:29.634365Z","steps":["trace[1472476655] 'process raft request' (duration: 314.842742ms)","trace[1472476655] 'compare' (duration: 68.151385ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:59:29.634416Z","caller":"traceutil/trace.go:171","msg":"trace[1290184334] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"382.667416ms","start":"2026-04-08T12:59:29.251723Z","end":"2026-04-08T12:59:29.63439Z","steps":["trace[1290184334] 'process raft request' (duration: 382.527792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:29.634576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:59:29.251106Z","time spent":"383.381359ms","remote":"127.0.0.1:52080","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-08T12:59:29.63458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:59:29.251707Z","time spent":"382.78635ms","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T12:59:30.150724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.329055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:59:30.151042Z","caller":"traceutil/trace.go:171","msg":"trace[990922405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1309; }","duration":"288.690996ms","start":"2026-04-08T12:59:29.862323Z","end":"2026-04-08T12:59:30.151014Z","steps":["trace[990922405] 'range keys from in-memory index tree' (duration: 288.228722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:30.859822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.88178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T12:59:30.859989Z","caller":"traceutil/trace.go:171","msg":"trace[1742701972] linearizableReadLoop","detail":"{readStateIndex:1368; appliedIndex:1367; }","duration":"210.278642ms","start":"2026-04-08T12:59:30.649666Z","end":"2026-04-08T12:59:30.859945Z","steps":["trace[1742701972] 'read index received' (duration: 82.107582ms)","trace[1742701972] 'applied index is now lower than readState.Index' (duration: 128.16592ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:59:30.860003Z","caller":"traceutil/trace.go:171","msg":"trace[1232889674] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"219.198192ms","start":"2026-04-08T12:59:30.640775Z","end":"2026-04-08T12:59:30.859973Z","steps":["trace[1232889674] 'process raft request' (duration: 91.048923ms)","trace[1232889674] 'compare' (duration: 127.761485ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:59:30.860107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.426126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-08T12:59:30.86014Z","caller":"traceutil/trace.go:171","msg":"trace[944922426] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1311; }","duration":"210.498809ms","start":"2026-04-08T12:59:30.64963Z","end":"2026-04-08T12:59:30.860129Z","steps":["trace[944922426] 'agreement among raft nodes before linearized reading' (duration: 210.391855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:31.105588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.977557ms","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-08T12:59:31.105674Z","caller":"traceutil/trace.go:171","msg":"trace[2078003083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1311; }","duration":"112.108871ms","start":"2026-04-08T12:59:30.993536Z","end":"2026-04-08T12:59:31.105645Z","steps":["trace[2078003083] 'range keys from in-memory index tree' (duration: 111.792361ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:33.176154Z","caller":"traceutil/trace.go:171","msg":"trace[1112365295] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"295.319652ms","start":"2026-04-08T12:59:32.88081Z","end":"2026-04-08T12:59:33.17613Z","steps":["trace[1112365295] 'process raft request' (duration: 295.173167ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:35.413307Z","caller":"traceutil/trace.go:171","msg":"trace[1304827227] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"179.696215ms","start":"2026-04-08T12:59:35.233588Z","end":"2026-04-08T12:59:35.413285Z","steps":["trace[1304827227] 'process raft request' (duration: 179.409396ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:41.431583Z","caller":"traceutil/trace.go:171","msg":"trace[898860123] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"163.254157ms","start":"2026-04-08T12:59:41.268296Z","end":"2026-04-08T12:59:41.43155Z","steps":["trace[898860123] 'process raft request' (duration: 163.100252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:41.672942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.807496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T12:59:41.673077Z","caller":"traceutil/trace.go:171","msg":"trace[1445058609] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"223.056802ms","start":"2026-04-08T12:59:41.450003Z","end":"2026-04-08T12:59:41.67306Z","steps":["trace[1445058609] 'read index received' (duration: 82.824402ms)","trace[1445058609] 'applied index is now lower than readState.Index' (duration: 140.23068ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:59:41.673174Z","caller":"traceutil/trace.go:171","msg":"trace[896865706] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"226.514996ms","start":"2026-04-08T12:59:41.446601Z","end":"2026-04-08T12:59:41.673116Z","steps":["trace[896865706] 'process raft request' (duration: 86.190263ms)","trace[896865706] 'compare' (duration: 139.582479ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:59:41.6733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.297631ms","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-08T12:59:41.673364Z","caller":"traceutil/trace.go:171","msg":"trace[1821474384] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1351; }","duration":"223.359473ms","start":"2026-04-08T12:59:41.449969Z","end":"2026-04-08T12:59:41.673329Z","steps":["trace[1821474384] 'agreement among raft nodes before linearized reading' (duration: 223.174716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:41.673354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.177801ms","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-08T12:59:41.673431Z","caller":"traceutil/trace.go:171","msg":"trace[1749236660] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1351; }","duration":"200.290345ms","start":"2026-04-08T12:59:41.473122Z","end":"2026-04-08T12:59:41.673412Z","steps":["trace[1749236660] 'agreement among raft nodes before linearized reading' (duration: 200.081577ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:42.186331Z","caller":"traceutil/trace.go:171","msg":"trace[791061272] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1411; }","duration":"431.116938ms","start":"2026-04-08T12:59:41.755184Z","end":"2026-04-08T12:59:42.186301Z","steps":["trace[791061272] 'read index received' (duration: 400.585433ms)","trace[791061272] 'applied index is now lower than readState.Index' (duration: 30.530045ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:59:42.186698Z","caller":"traceutil/trace.go:171","msg":"trace[1023222093] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"508.44056ms","start":"2026-04-08T12:59:41.678238Z","end":"2026-04-08T12:59:42.186678Z","steps":["trace[1023222093] 'process raft request' (duration: 477.515493ms)","trace[1023222093] 'compare' (duration: 30.416621ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:59:42.186826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:59:41.678229Z","time spent":"508.505213ms","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T12:59:42.187168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.977666ms","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-08T12:59:42.187224Z","caller":"traceutil/trace.go:171","msg":"trace[1678110387] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1353; }","duration":"432.059169ms","start":"2026-04-08T12:59:41.755148Z","end":"2026-04-08T12:59:42.187208Z","steps":["trace[1678110387] 'agreement among raft nodes before linearized reading' (duration: 431.909024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:42.187267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:59:41.755133Z","time spent":"432.124381ms","remote":"127.0.0.1:52080","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-08T12:59:42.200702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.550258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T12:59:42.200762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.695196ms","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-08T12:59:42.200817Z","caller":"traceutil/trace.go:171","msg":"trace[27669928] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1354; }","duration":"266.694813ms","start":"2026-04-08T12:59:41.934094Z","end":"2026-04-08T12:59:42.200789Z","steps":["trace[27669928] 'agreement among raft nodes before linearized reading' (duration: 266.512108ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:42.200867Z","caller":"traceutil/trace.go:171","msg":"trace[1358874892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1354; }","duration":"434.849481ms","start":"2026-04-08T12:59:41.765993Z","end":"2026-04-08T12:59:42.200842Z","steps":["trace[1358874892] 'agreement among raft nodes before linearized reading' (duration: 434.545421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:42.20093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:59:41.765982Z","time spent":"434.933314ms","remote":"127.0.0.1:52080","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-08T12:59:42.200976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.843292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:59:42.201046Z","caller":"traceutil/trace.go:171","msg":"trace[451877799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1354; }","duration":"337.943616ms","start":"2026-04-08T12:59:41.863085Z","end":"2026-04-08T12:59:42.201029Z","steps":["trace[451877799] 'agreement among raft nodes before linearized reading' (duration: 337.841003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:42.201098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:59:41.863066Z","time spent":"338.018928ms","remote":"127.0.0.1:51820","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-08T12:59:42.201286Z","caller":"traceutil/trace.go:171","msg":"trace[950332611] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"369.09587ms","start":"2026-04-08T12:59:41.832165Z","end":"2026-04-08T12:59:42.201261Z","steps":["trace[950332611] 'process raft request' (duration: 368.17408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:42.201428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:59:41.832145Z","time spent":"369.209384ms","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T12:59:43.540197Z","caller":"traceutil/trace.go:171","msg":"trace[1154658059] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"104.16302ms","start":"2026-04-08T12:59:43.436012Z","end":"2026-04-08T12:59:43.540175Z","steps":["trace[1154658059] 'process raft request' (duration: 104.036116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:43.831219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.798777ms","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-08T12:59:43.831296Z","caller":"traceutil/trace.go:171","msg":"trace[1125645696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1357; }","duration":"154.920721ms","start":"2026-04-08T12:59:43.676352Z","end":"2026-04-08T12:59:43.831273Z","steps":["trace[1125645696] 'range keys from in-memory index tree' (duration: 154.646352ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:44.069162Z","caller":"traceutil/trace.go:171","msg":"trace[1807981397] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"233.833603ms","start":"2026-04-08T12:59:43.835305Z","end":"2026-04-08T12:59:44.069139Z","steps":["trace[1807981397] 'process raft request' (duration: 233.693108ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:44.076042Z","caller":"traceutil/trace.go:171","msg":"trace[50986958] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1416; }","duration":"211.910491ms","start":"2026-04-08T12:59:43.863937Z","end":"2026-04-08T12:59:44.075847Z","steps":["trace[50986958] 'read index received' (duration: 205.060535ms)","trace[50986958] 'applied index is now lower than readState.Index' (duration: 6.848565ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:59:44.076124Z","caller":"traceutil/trace.go:171","msg":"trace[1984249119] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"240.74416ms","start":"2026-04-08T12:59:43.835366Z","end":"2026-04-08T12:59:44.076111Z","steps":["trace[1984249119] 'process raft request' (duration: 240.331947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:44.076191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.239812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:59:44.076248Z","caller":"traceutil/trace.go:171","msg":"trace[1356203249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1359; }","duration":"212.362275ms","start":"2026-04-08T12:59:43.863871Z","end":"2026-04-08T12:59:44.076233Z","steps":["trace[1356203249] 'agreement among raft nodes before linearized reading' (duration: 212.257222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:58.648773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.428703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node\" ","response":"range_response_count:1 size:603"} {"level":"info","ts":"2026-04-08T12:59:58.648897Z","caller":"traceutil/trace.go:171","msg":"trace[1104912827] range","detail":"{range_begin:/registry/clusterrolebindings/system:node; range_end:; response_count:1; response_revision:1412; }","duration":"121.589278ms","start":"2026-04-08T12:59:58.527291Z","end":"2026-04-08T12:59:58.64888Z","steps":["trace[1104912827] 'range keys from in-memory index tree' (duration: 121.337661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:58.6491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.826779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node-proxier\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-04-08T12:59:58.649197Z","caller":"traceutil/trace.go:171","msg":"trace[1281016506] range","detail":"{range_begin:/registry/clusterrolebindings/system:node-proxier; range_end:; response_count:1; response_revision:1412; }","duration":"134.959364ms","start":"2026-04-08T12:59:58.514213Z","end":"2026-04-08T12:59:58.649173Z","steps":["trace[1281016506] 'range keys from in-memory index tree' (duration: 134.709266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:58.649198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.922504ms","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-08T12:59:58.649284Z","caller":"traceutil/trace.go:171","msg":"trace[1424609255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1412; }","duration":"187.032328ms","start":"2026-04-08T12:59:58.462224Z","end":"2026-04-08T12:59:58.649256Z","steps":["trace[1424609255] 'range keys from in-memory index tree' (duration: 186.554832ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:59.862436Z","caller":"traceutil/trace.go:171","msg":"trace[1382798580] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"128.183611ms","start":"2026-04-08T12:59:59.734229Z","end":"2026-04-08T12:59:59.862412Z","steps":["trace[1382798580] 'process raft request' (duration: 127.885941ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:08.646671Z","caller":"traceutil/trace.go:171","msg":"trace[1443559205] linearizableReadLoop","detail":"{readStateIndex:1507; appliedIndex:1506; }","duration":"151.256987ms","start":"2026-04-08T13:00:08.49539Z","end":"2026-04-08T13:00:08.646647Z","steps":["trace[1443559205] 'read index received' (duration: 151.131333ms)","trace[1443559205] 'applied index is now lower than readState.Index' (duration: 124.904µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:00:08.646787Z","caller":"traceutil/trace.go:171","msg":"trace[2057892879] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"152.175467ms","start":"2026-04-08T13:00:08.494598Z","end":"2026-04-08T13:00:08.646774Z","steps":["trace[2057892879] 'process raft request' (duration: 151.930569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:00:08.647078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.664661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kubeadm:kubelet-config\" ","response":"range_response_count:1 size:345"} {"level":"info","ts":"2026-04-08T13:00:08.647155Z","caller":"traceutil/trace.go:171","msg":"trace[2140998750] range","detail":"{range_begin:/registry/roles/kube-system/kubeadm:kubelet-config; range_end:; response_count:1; response_revision:1443; }","duration":"151.793646ms","start":"2026-04-08T13:00:08.495343Z","end":"2026-04-08T13:00:08.647137Z","steps":["trace[2140998750] 'agreement among raft nodes before linearized reading' (duration: 151.673002ms)"],"step_count":1}