{"level":"warn","ts":"2026-04-21T18:09:30.567059Z","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-21T18:09:30.567159Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.200: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.200:2380","--initial-cluster=instance=https://199.19.213.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.200: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-21T18:09:30.567236Z","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-21T18:09:30.567251Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-04-21T18:09:30.567271Z","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-21T18:09:30.568079Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"]} {"level":"info","ts":"2026-04-21T18:09:30.568174Z","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.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.200: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-21T18:09:30.573701Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.260648ms"} {"level":"info","ts":"2026-04-21T18:09:30.580956Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3363378c2227fa42","cluster-id":"7faf957b2e4cf761"} {"level":"info","ts":"2026-04-21T18:09:30.581017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T18:09:30.581042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 0"} {"level":"info","ts":"2026-04-21T18:09:30.581054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3363378c2227fa42 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T18:09:30.58107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 1"} {"level":"info","ts":"2026-04-21T18:09:30.58111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"warn","ts":"2026-04-21T18:09:30.587688Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T18:09:30.589281Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T18:09:30.590325Z","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-21T18:09:30.592383Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3363378c2227fa42","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T18:09:30.59285Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3363378c2227fa42","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T18:09:30.593019Z","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-21T18:09:30.593057Z","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-21T18:09:30.593066Z","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-21T18:09:30.594912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"info","ts":"2026-04-21T18:09:30.596615Z","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-21T18:09:30.596739Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","added-peer-id":"3363378c2227fa42","added-peer-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-04-21T18:09:30.596838Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-04-21T18:09:30.596851Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-04-21T18:09:30.596961Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3363378c2227fa42","initial-advertise-peer-urls":["https://199.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T18:09:30.597002Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T18:09:31.182291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T18:09:31.182355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T18:09:31.182378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgPreVoteResp from 3363378c2227fa42 at term 1"} {"level":"info","ts":"2026-04-21T18:09:31.182393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became candidate at term 2"} {"level":"info","ts":"2026-04-21T18:09:31.1824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgVoteResp from 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-04-21T18:09:31.182411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became leader at term 2"} {"level":"info","ts":"2026-04-21T18:09:31.18242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3363378c2227fa42 elected leader 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-04-21T18:09:31.183438Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:09:31.184122Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3363378c2227fa42","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.200:2379]}","request-path":"/0/members/3363378c2227fa42/attributes","cluster-id":"7faf957b2e4cf761","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T18:09:31.184276Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T18:09:31.184307Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T18:09:31.184932Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:09:31.185014Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:09:31.185042Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:09:31.187534Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.200:2379"} {"level":"info","ts":"2026-04-21T18:09:31.187579Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T18:09:31.187701Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T18:09:31.187734Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T18:09:36.327216Z","caller":"traceutil/trace.go:171","msg":"trace[1946023555] linearizableReadLoop","detail":"{readStateIndex:300; appliedIndex:299; }","duration":"106.373604ms","start":"2026-04-21T18:09:36.220815Z","end":"2026-04-21T18:09:36.327189Z","steps":["trace[1946023555] 'read index received' (duration: 53.360635ms)","trace[1946023555] 'applied index is now lower than readState.Index' (duration: 53.009529ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:09:36.327252Z","caller":"traceutil/trace.go:171","msg":"trace[1695177987] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"107.131069ms","start":"2026-04-21T18:09:36.220082Z","end":"2026-04-21T18:09:36.327213Z","steps":["trace[1695177987] 'process raft request' (duration: 54.12531ms)","trace[1695177987] 'compare' (duration: 52.853336ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:09:36.327367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.524597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:09:36.32745Z","caller":"traceutil/trace.go:171","msg":"trace[91312359] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:0; response_revision:294; }","duration":"106.64673ms","start":"2026-04-21T18:09:36.22079Z","end":"2026-04-21T18:09:36.327437Z","steps":["trace[91312359] 'agreement among raft nodes before linearized reading' (duration: 106.506257ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:36.332704Z","caller":"traceutil/trace.go:171","msg":"trace[1847677728] transaction","detail":"{read_only:false; number_of_response:0; response_revision:294; }","duration":"111.729643ms","start":"2026-04-21T18:09:36.220943Z","end":"2026-04-21T18:09:36.332672Z","steps":["trace[1847677728] 'process raft request' (duration: 111.498789ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:36.557684Z","caller":"traceutil/trace.go:171","msg":"trace[56584758] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"166.21827ms","start":"2026-04-21T18:09:36.391435Z","end":"2026-04-21T18:09:36.557654Z","steps":["trace[56584758] 'process raft request' (duration: 90.018817ms)","trace[56584758] 'compare' (duration: 75.818826ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:09:36.557813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.854077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-21T18:09:36.557888Z","caller":"traceutil/trace.go:171","msg":"trace[2103707508] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:305; }","duration":"133.99727ms","start":"2026-04-21T18:09:36.423874Z","end":"2026-04-21T18:09:36.557871Z","steps":["trace[2103707508] 'agreement among raft nodes before linearized reading' (duration: 133.817697ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:09:36.557686Z","caller":"traceutil/trace.go:171","msg":"trace[249922503] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:311; }","duration":"133.722705ms","start":"2026-04-21T18:09:36.42394Z","end":"2026-04-21T18:09:36.557663Z","steps":["trace[249922503] 'read index received' (duration: 57.478961ms)","trace[249922503] 'applied index is now lower than readState.Index' (duration: 76.241104ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:09:57.206572Z","caller":"traceutil/trace.go:171","msg":"trace[672019956] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"311.65354ms","start":"2026-04-21T18:09:56.894888Z","end":"2026-04-21T18:09:57.206541Z","steps":["trace[672019956] 'process raft request' (duration: 311.464836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:09:57.207411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:09:56.894873Z","time spent":"311.893034ms","remote":"127.0.0.1:46170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:10:04.14566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.915985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:10:04.145851Z","caller":"traceutil/trace.go:171","msg":"trace[1643846639] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"740.99274ms","start":"2026-04-21T18:10:03.40484Z","end":"2026-04-21T18:10:04.145833Z","steps":["trace[1643846639] 'read index received' (duration: 319.580636ms)","trace[1643846639] 'applied index is now lower than readState.Index' (duration: 421.410234ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:04.145967Z","caller":"traceutil/trace.go:171","msg":"trace[934187419] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"805.412176ms","start":"2026-04-21T18:10:03.340543Z","end":"2026-04-21T18:10:04.145955Z","steps":["trace[934187419] 'process raft request' (duration: 383.937681ms)","trace[934187419] 'compare' (duration: 420.59431ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:10:04.146058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:03.340508Z","time spent":"805.515128ms","remote":"127.0.0.1:46170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:10:04.146339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.50544ms","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-21T18:10:04.146413Z","caller":"traceutil/trace.go:171","msg":"trace[1792966385] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:514; }","duration":"741.577401ms","start":"2026-04-21T18:10:03.404822Z","end":"2026-04-21T18:10:04.146399Z","steps":["trace[1792966385] 'agreement among raft nodes before linearized reading' (duration: 741.477569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:04.146471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:03.404762Z","time spent":"741.699163ms","remote":"127.0.0.1:46022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-21T18:10:04.146589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.348346ms","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-21T18:10:04.14669Z","caller":"traceutil/trace.go:171","msg":"trace[2085834774] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:514; }","duration":"721.522439ms","start":"2026-04-21T18:10:03.425146Z","end":"2026-04-21T18:10:04.146668Z","steps":["trace[2085834774] 'agreement among raft nodes before linearized reading' (duration: 721.324186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:04.146732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:03.425115Z","time spent":"721.608331ms","remote":"127.0.0.1:46170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T18:10:04.146937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.104232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-21T18:10:04.147038Z","caller":"traceutil/trace.go:171","msg":"trace[1599361462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"630.251094ms","start":"2026-04-21T18:10:03.516765Z","end":"2026-04-21T18:10:04.147016Z","steps":["trace[1599361462] 'agreement among raft nodes before linearized reading' (duration: 630.018239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:04.147248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:03.516737Z","time spent":"630.497068ms","remote":"127.0.0.1:46170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-21T18:10:06.421752Z","caller":"traceutil/trace.go:171","msg":"trace[1043953777] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"416.210923ms","start":"2026-04-21T18:10:06.00549Z","end":"2026-04-21T18:10:06.421701Z","steps":["trace[1043953777] 'process raft request' (duration: 402.401083ms)","trace[1043953777] 'compare' (duration: 13.540741ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:06.421857Z","caller":"traceutil/trace.go:171","msg":"trace[1393045788] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"404.176133ms","start":"2026-04-21T18:10:06.017641Z","end":"2026-04-21T18:10:06.421817Z","steps":["trace[1393045788] 'read index received' (duration: 390.248539ms)","trace[1393045788] 'applied index is now lower than readState.Index' (duration: 13.924444ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:10:06.422143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.466563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-04-21T18:10:06.422191Z","caller":"traceutil/trace.go:171","msg":"trace[1628501524] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:614; }","duration":"404.572607ms","start":"2026-04-21T18:10:06.017605Z","end":"2026-04-21T18:10:06.422178Z","steps":["trace[1628501524] 'agreement among raft nodes before linearized reading' (duration: 404.280467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:06.422215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:06.017589Z","time spent":"404.620108ms","remote":"127.0.0.1:45966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":151258,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-21T18:10:06.42238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.117904ms","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-21T18:10:06.422367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.525741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:10:06.422433Z","caller":"traceutil/trace.go:171","msg":"trace[904768684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"115.227428ms","start":"2026-04-21T18:10:06.307195Z","end":"2026-04-21T18:10:06.422422Z","steps":["trace[904768684] 'agreement among raft nodes before linearized reading' (duration: 115.060863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:06.424251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:06.005433Z","time spent":"416.42131ms","remote":"127.0.0.1:45966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:10:06.422913Z","caller":"traceutil/trace.go:171","msg":"trace[1176373950] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"339.106681ms","start":"2026-04-21T18:10:06.083324Z","end":"2026-04-21T18:10:06.422431Z","steps":["trace[1176373950] 'process raft request' (duration: 338.483339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:06.425184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:06.083289Z","time spent":"341.772741ms","remote":"127.0.0.1:45966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:10:06.423011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.962111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-21T18:10:06.425389Z","caller":"traceutil/trace.go:171","msg":"trace[1411133869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"190.355843ms","start":"2026-04-21T18:10:06.235Z","end":"2026-04-21T18:10:06.425356Z","steps":["trace[1411133869] 'agreement among raft nodes before linearized reading' (duration: 187.377902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:06.423155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.676973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-27qsf\" ","response":"range_response_count:1 size:4888"} {"level":"info","ts":"2026-04-21T18:10:06.425673Z","caller":"traceutil/trace.go:171","msg":"trace[440354037] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-27qsf; range_end:; response_count:1; response_revision:616; }","duration":"183.201269ms","start":"2026-04-21T18:10:06.242444Z","end":"2026-04-21T18:10:06.425645Z","steps":["trace[440354037] 'agreement among raft nodes before linearized reading' (duration: 180.017461ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:06.423183Z","caller":"traceutil/trace.go:171","msg":"trace[1170266898] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:616; }","duration":"233.712017ms","start":"2026-04-21T18:10:06.18874Z","end":"2026-04-21T18:10:06.422452Z","steps":["trace[1170266898] 'agreement among raft nodes before linearized reading' (duration: 233.513471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:06.424107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.277884ms","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-21T18:10:06.426844Z","caller":"traceutil/trace.go:171","msg":"trace[1320212789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"190.513398ms","start":"2026-04-21T18:10:06.236055Z","end":"2026-04-21T18:10:06.426568Z","steps":["trace[1320212789] 'agreement among raft nodes before linearized reading' (duration: 186.214342ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:06.827407Z","caller":"traceutil/trace.go:171","msg":"trace[958013866] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"362.692862ms","start":"2026-04-21T18:10:06.464688Z","end":"2026-04-21T18:10:06.827381Z","steps":["trace[958013866] 'read index received' (duration: 15.517507ms)","trace[958013866] 'applied index is now lower than readState.Index' (duration: 347.174725ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:06.82759Z","caller":"traceutil/trace.go:171","msg":"trace[1174098193] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"389.774274ms","start":"2026-04-21T18:10:06.437807Z","end":"2026-04-21T18:10:06.827582Z","steps":["trace[1174098193] 'process raft request' (duration: 383.863103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:06.827694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:06.437799Z","time spent":"389.815215ms","remote":"127.0.0.1:46090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4679,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:10:06.827798Z","caller":"traceutil/trace.go:171","msg":"trace[146997995] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"340.357984ms","start":"2026-04-21T18:10:06.487419Z","end":"2026-04-21T18:10:06.827777Z","steps":["trace[146997995] 'process raft request' (duration: 339.998601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:06.827914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:06.487383Z","time spent":"340.469577ms","remote":"127.0.0.1:45966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:10:06.828111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.425118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-04-21T18:10:06.828112Z","caller":"traceutil/trace.go:171","msg":"trace[453660702] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"325.908622ms","start":"2026-04-21T18:10:06.502175Z","end":"2026-04-21T18:10:06.828084Z","steps":["trace[453660702] 'process raft request' (duration: 325.513639ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:06.828138Z","caller":"traceutil/trace.go:171","msg":"trace[1522289240] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:622; }","duration":"363.48297ms","start":"2026-04-21T18:10:06.464648Z","end":"2026-04-21T18:10:06.828131Z","steps":["trace[1522289240] 'agreement among raft nodes before linearized reading' (duration: 362.943851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:06.828156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:06.46463Z","time spent":"363.52307ms","remote":"127.0.0.1:45966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":7730,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-21T18:10:06.828201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:06.502138Z","time spent":"326.014425ms","remote":"127.0.0.1:45966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:10:06.828242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.326977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:10:06.828259Z","caller":"traceutil/trace.go:171","msg":"trace[1279197776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"299.37531ms","start":"2026-04-21T18:10:06.528879Z","end":"2026-04-21T18:10:06.828254Z","steps":["trace[1279197776] 'agreement among raft nodes before linearized reading' (duration: 299.336929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:06.828331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.002917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:10:06.828349Z","caller":"traceutil/trace.go:171","msg":"trace[1713581201] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:623; }","duration":"239.065959ms","start":"2026-04-21T18:10:06.589278Z","end":"2026-04-21T18:10:06.828343Z","steps":["trace[1713581201] 'agreement among raft nodes before linearized reading' (duration: 239.041948ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:06.828353Z","caller":"traceutil/trace.go:171","msg":"trace[577309456] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"129.817054ms","start":"2026-04-21T18:10:06.698524Z","end":"2026-04-21T18:10:06.828341Z","steps":["trace[577309456] 'process raft request' (duration: 129.498403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:11.100593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.266673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:10:11.100794Z","caller":"traceutil/trace.go:171","msg":"trace[1733404795] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"360.620725ms","start":"2026-04-21T18:10:10.740143Z","end":"2026-04-21T18:10:11.100764Z","steps":["trace[1733404795] 'process raft request' (duration: 137.048122ms)","trace[1733404795] 'compare' (duration: 223.042816ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:10:11.100996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:10.740118Z","time spent":"360.725839ms","remote":"127.0.0.1:46238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:10:11.583339Z","caller":"traceutil/trace.go:171","msg":"trace[868906033] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"111.656579ms","start":"2026-04-21T18:10:11.471662Z","end":"2026-04-21T18:10:11.583319Z","steps":["trace[868906033] 'process raft request' (duration: 111.548915ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:11.889895Z","caller":"traceutil/trace.go:171","msg":"trace[1662470643] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"323.316331ms","start":"2026-04-21T18:10:11.566549Z","end":"2026-04-21T18:10:11.889865Z","steps":["trace[1662470643] 'process raft request' (duration: 322.735622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:11.890067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:11.566518Z","time spent":"323.461646ms","remote":"127.0.0.1:46022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:10:12.124887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.311791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:10:12.12501Z","caller":"traceutil/trace.go:171","msg":"trace[1528141218] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"165.762469ms","start":"2026-04-21T18:10:11.959228Z","end":"2026-04-21T18:10:12.124991Z","steps":["trace[1528141218] 'process raft request' (duration: 47.276442ms)","trace[1528141218] 'compare' (duration: 118.166436ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:14.62605Z","caller":"traceutil/trace.go:171","msg":"trace[1617103349] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:714; }","duration":"179.081317ms","start":"2026-04-21T18:10:14.446935Z","end":"2026-04-21T18:10:14.626016Z","steps":["trace[1617103349] 'read index received' (duration: 178.87752ms)","trace[1617103349] 'applied index is now lower than readState.Index' (duration: 202.497µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:14.626152Z","caller":"traceutil/trace.go:171","msg":"trace[1263731131] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"338.117597ms","start":"2026-04-21T18:10:14.288002Z","end":"2026-04-21T18:10:14.626119Z","steps":["trace[1263731131] 'process raft request' (duration: 337.815337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:14.626268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.312205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-21T18:10:14.626337Z","caller":"traceutil/trace.go:171","msg":"trace[1934786822] range","detail":"{range_begin:/registry/masterleases/199.19.213.200; range_end:; response_count:1; response_revision:690; }","duration":"179.452999ms","start":"2026-04-21T18:10:14.446867Z","end":"2026-04-21T18:10:14.62632Z","steps":["trace[1934786822] 'agreement among raft nodes before linearized reading' (duration: 179.320035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:14.62633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:14.287966Z","time spent":"338.270722ms","remote":"127.0.0.1:46090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:10:14.626477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.588211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-21T18:10:14.626482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.63923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-21T18:10:14.626591Z","caller":"traceutil/trace.go:171","msg":"trace[1879135111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:690; }","duration":"155.759696ms","start":"2026-04-21T18:10:14.470797Z","end":"2026-04-21T18:10:14.626557Z","steps":["trace[1879135111] 'agreement among raft nodes before linearized reading' (duration: 155.440456ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:14.626672Z","caller":"traceutil/trace.go:171","msg":"trace[2091798144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:690; }","duration":"154.846856ms","start":"2026-04-21T18:10:14.471809Z","end":"2026-04-21T18:10:14.626656Z","steps":["trace[2091798144] 'agreement among raft nodes before linearized reading' (duration: 154.563097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:14.626462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.189033ms","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-21T18:10:14.626783Z","caller":"traceutil/trace.go:171","msg":"trace[342417417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:690; }","duration":"112.572755ms","start":"2026-04-21T18:10:14.514197Z","end":"2026-04-21T18:10:14.62677Z","steps":["trace[342417417] 'agreement among raft nodes before linearized reading' (duration: 112.060648ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:14.747807Z","caller":"traceutil/trace.go:171","msg":"trace[701373175] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"116.289134ms","start":"2026-04-21T18:10:14.631484Z","end":"2026-04-21T18:10:14.747773Z","steps":["trace[701373175] 'process raft request' (duration: 102.289803ms)","trace[701373175] 'compare' (duration: 13.405662ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:14.748232Z","caller":"traceutil/trace.go:171","msg":"trace[492377005] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"115.702076ms","start":"2026-04-21T18:10:14.632502Z","end":"2026-04-21T18:10:14.748204Z","steps":["trace[492377005] 'process raft request' (duration: 115.568572ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:14.748254Z","caller":"traceutil/trace.go:171","msg":"trace[2120912270] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"116.544203ms","start":"2026-04-21T18:10:14.631682Z","end":"2026-04-21T18:10:14.748226Z","steps":["trace[2120912270] 'process raft request' (duration: 116.031506ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:14.749613Z","caller":"traceutil/trace.go:171","msg":"trace[1567290885] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"106.850751ms","start":"2026-04-21T18:10:14.642747Z","end":"2026-04-21T18:10:14.749597Z","steps":["trace[1567290885] 'process raft request' (duration: 106.776939ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:15.987839Z","caller":"traceutil/trace.go:171","msg":"trace[805809505] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:720; }","duration":"462.003051ms","start":"2026-04-21T18:10:15.525816Z","end":"2026-04-21T18:10:15.987819Z","steps":["trace[805809505] 'read index received' (duration: 461.828825ms)","trace[805809505] 'applied index is now lower than readState.Index' (duration: 171.566µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:15.987895Z","caller":"traceutil/trace.go:171","msg":"trace[268213942] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"620.621945ms","start":"2026-04-21T18:10:15.367237Z","end":"2026-04-21T18:10:15.987859Z","steps":["trace[268213942] 'process raft request' (duration: 620.45994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:15.98798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.149615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:10:15.988007Z","caller":"traceutil/trace.go:171","msg":"trace[2021912043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:695; }","duration":"462.235359ms","start":"2026-04-21T18:10:15.525765Z","end":"2026-04-21T18:10:15.988Z","steps":["trace[2021912043] 'agreement among raft nodes before linearized reading' (duration: 462.162786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:15.988034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:15.525741Z","time spent":"462.286399ms","remote":"127.0.0.1:45902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T18:10:15.988053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:15.367225Z","time spent":"620.734929ms","remote":"127.0.0.1:46170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:10:15.988231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.149284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-21T18:10:15.988293Z","caller":"traceutil/trace.go:171","msg":"trace[1797274958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:695; }","duration":"391.214896ms","start":"2026-04-21T18:10:15.597064Z","end":"2026-04-21T18:10:15.988279Z","steps":["trace[1797274958] 'agreement among raft nodes before linearized reading' (duration: 391.02126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:15.98833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:15.596279Z","time spent":"392.042943ms","remote":"127.0.0.1:46170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-21T18:10:17.027991Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18033149242480646903,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T18:10:17.063863Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.071350744s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T18:10:17.088085Z","caller":"traceutil/trace.go:171","msg":"trace[656803631] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"1.095656872s","start":"2026-04-21T18:10:15.9924Z","end":"2026-04-21T18:10:17.088057Z","steps":["trace[656803631] 'process raft request' (duration: 1.071801558s)","trace[656803631] 'compare' (duration: 23.679708ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:17.088123Z","caller":"traceutil/trace.go:171","msg":"trace[999687641] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:721; }","duration":"560.618983ms","start":"2026-04-21T18:10:16.527471Z","end":"2026-04-21T18:10:17.08809Z","steps":["trace[999687641] 'read index received' (duration: 536.696627ms)","trace[999687641] 'applied index is now lower than readState.Index' (duration: 23.920006ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:10:17.088215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:15.992372Z","time spent":"1.095779996s","remote":"127.0.0.1:46170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:10:17.088351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.882672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:10:17.088385Z","caller":"traceutil/trace.go:171","msg":"trace[1515142031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"560.947734ms","start":"2026-04-21T18:10:16.527429Z","end":"2026-04-21T18:10:17.088377Z","steps":["trace[1515142031] 'agreement among raft nodes before linearized reading' (duration: 560.764538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:17.088386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.339672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-21T18:10:17.088386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.038716ms","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-21T18:10:17.088428Z","caller":"traceutil/trace.go:171","msg":"trace[1440804841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:697; }","duration":"335.406344ms","start":"2026-04-21T18:10:16.75301Z","end":"2026-04-21T18:10:17.088416Z","steps":["trace[1440804841] 'agreement among raft nodes before linearized reading' (duration: 335.28279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:17.088406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:16.527389Z","time spent":"561.012396ms","remote":"127.0.0.1:45902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T18:10:17.088461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:16.753003Z","time spent":"335.449406ms","remote":"127.0.0.1:46170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-21T18:10:17.088459Z","caller":"traceutil/trace.go:171","msg":"trace[1688377169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:697; }","duration":"337.135019ms","start":"2026-04-21T18:10:16.751291Z","end":"2026-04-21T18:10:17.088426Z","steps":["trace[1688377169] 'agreement among raft nodes before linearized reading' (duration: 336.954524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:17.088505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:16.75126Z","time spent":"337.232343ms","remote":"127.0.0.1:46170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T18:10:17.089223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.481221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-21T18:10:17.089498Z","caller":"traceutil/trace.go:171","msg":"trace[1230751977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:697; }","duration":"337.78138ms","start":"2026-04-21T18:10:16.751697Z","end":"2026-04-21T18:10:17.089479Z","steps":["trace[1230751977] 'agreement among raft nodes before linearized reading' (duration: 336.647794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:17.089609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:16.751691Z","time spent":"337.899163ms","remote":"127.0.0.1:46170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-21T18:10:33.245271Z","caller":"traceutil/trace.go:171","msg":"trace[923735937] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"104.779241ms","start":"2026-04-21T18:10:33.140442Z","end":"2026-04-21T18:10:33.245221Z","steps":["trace[923735937] 'process raft request' (duration: 104.521793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:34.418715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.918682ms","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-21T18:10:34.418805Z","caller":"traceutil/trace.go:171","msg":"trace[1706888527] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:814; }","duration":"349.071246ms","start":"2026-04-21T18:10:34.069713Z","end":"2026-04-21T18:10:34.418784Z","steps":["trace[1706888527] 'range keys from in-memory index tree' (duration: 348.467878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:34.418846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:10:34.069686Z","time spent":"349.151508ms","remote":"127.0.0.1:46170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T18:10:34.612704Z","caller":"traceutil/trace.go:171","msg":"trace[1551344686] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:844; }","duration":"163.831212ms","start":"2026-04-21T18:10:34.448844Z","end":"2026-04-21T18:10:34.612676Z","steps":["trace[1551344686] 'read index received' (duration: 163.729139ms)","trace[1551344686] 'applied index is now lower than readState.Index' (duration: 100.973µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:34.612838Z","caller":"traceutil/trace.go:171","msg":"trace[1523367101] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"189.700902ms","start":"2026-04-21T18:10:34.423087Z","end":"2026-04-21T18:10:34.612788Z","steps":["trace[1523367101] 'process raft request' (duration: 189.378512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:10:34.612946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.089509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-21T18:10:34.613168Z","caller":"traceutil/trace.go:171","msg":"trace[1143997163] range","detail":"{range_begin:/registry/masterleases/199.19.213.200; range_end:; response_count:1; response_revision:815; }","duration":"164.398388ms","start":"2026-04-21T18:10:34.44874Z","end":"2026-04-21T18:10:34.613138Z","steps":["trace[1143997163] 'agreement among raft nodes before linearized reading' (duration: 164.068178ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:34.614616Z","caller":"traceutil/trace.go:171","msg":"trace[1539003325] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"133.254338ms","start":"2026-04-21T18:10:34.481325Z","end":"2026-04-21T18:10:34.614579Z","steps":["trace[1539003325] 'process raft request' (duration: 132.941629ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:42.763019Z","caller":"traceutil/trace.go:171","msg":"trace[1046756656] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"107.953469ms","start":"2026-04-21T18:10:42.654853Z","end":"2026-04-21T18:10:42.762807Z","steps":["trace[1046756656] 'process raft request' (duration: 107.749144ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:43.527966Z","caller":"traceutil/trace.go:171","msg":"trace[159781075] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"158.205237ms","start":"2026-04-21T18:10:43.369738Z","end":"2026-04-21T18:10:43.527943Z","steps":["trace[159781075] 'process raft request' (duration: 104.517862ms)","trace[159781075] 'compare' (duration: 53.582782ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:10:43.557322Z","caller":"traceutil/trace.go:171","msg":"trace[19221403] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:934; }","duration":"136.73692ms","start":"2026-04-21T18:10:43.420544Z","end":"2026-04-21T18:10:43.557281Z","steps":["trace[19221403] 'read index received' (duration: 53.765937ms)","trace[19221403] 'applied index is now lower than readState.Index' (duration: 82.969413ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:10:43.557701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.111461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:10:43.557804Z","caller":"traceutil/trace.go:171","msg":"trace[309580419] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:903; }","duration":"137.287266ms","start":"2026-04-21T18:10:43.420502Z","end":"2026-04-21T18:10:43.557789Z","steps":["trace[309580419] 'agreement among raft nodes before linearized reading' (duration: 137.05487ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:43.558242Z","caller":"traceutil/trace.go:171","msg":"trace[355095335] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"155.685598ms","start":"2026-04-21T18:10:43.402155Z","end":"2026-04-21T18:10:43.55784Z","steps":["trace[355095335] 'process raft request' (duration: 154.946077ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:44.086823Z","caller":"traceutil/trace.go:171","msg":"trace[1583206546] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"211.91147ms","start":"2026-04-21T18:10:43.874891Z","end":"2026-04-21T18:10:44.086802Z","steps":["trace[1583206546] 'process raft request' (duration: 211.760246ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:49.716075Z","caller":"traceutil/trace.go:171","msg":"trace[2023118529] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"161.02425ms","start":"2026-04-21T18:10:49.555021Z","end":"2026-04-21T18:10:49.716045Z","steps":["trace[2023118529] 'process raft request' (duration: 160.865586ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:10:49.718472Z","caller":"traceutil/trace.go:171","msg":"trace[493385257] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"133.009577ms","start":"2026-04-21T18:10:49.585404Z","end":"2026-04-21T18:10:49.718414Z","steps":["trace[493385257] 'process raft request' (duration: 132.662838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:11:34.108532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.033976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-21T18:11:34.108658Z","caller":"traceutil/trace.go:171","msg":"trace[1113038473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1145; }","duration":"110.20744ms","start":"2026-04-21T18:11:33.998432Z","end":"2026-04-21T18:11:34.10864Z","steps":["trace[1113038473] 'range keys from in-memory index tree' (duration: 109.868012ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:11:53.868652Z","caller":"traceutil/trace.go:171","msg":"trace[1237892442] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"140.904055ms","start":"2026-04-21T18:11:53.727673Z","end":"2026-04-21T18:11:53.868577Z","steps":["trace[1237892442] 'process raft request' (duration: 140.712881ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:12:16.923305Z","caller":"traceutil/trace.go:171","msg":"trace[1561256010] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"127.066197ms","start":"2026-04-21T18:12:16.796217Z","end":"2026-04-21T18:12:16.923283Z","steps":["trace[1561256010] 'process raft request' (duration: 126.924794ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:12:24.118823Z","caller":"traceutil/trace.go:171","msg":"trace[340428938] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"128.669651ms","start":"2026-04-21T18:12:23.990112Z","end":"2026-04-21T18:12:24.118782Z","steps":["trace[340428938] 'process raft request' (duration: 128.372945ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:12:24.78147Z","caller":"traceutil/trace.go:171","msg":"trace[934607868] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"311.118655ms","start":"2026-04-21T18:12:24.470321Z","end":"2026-04-21T18:12:24.78144Z","steps":["trace[934607868] 'process raft request' (duration: 251.162455ms)","trace[934607868] 'compare' (duration: 59.849188ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:12:24.781645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:12:24.470262Z","time spent":"311.299048ms","remote":"127.0.0.1:46170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:12:24.796238Z","caller":"traceutil/trace.go:171","msg":"trace[1205058323] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"291.839089ms","start":"2026-04-21T18:12:24.504363Z","end":"2026-04-21T18:12:24.796202Z","steps":["trace[1205058323] 'process raft request' (duration: 291.560123ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:12:26.312686Z","caller":"traceutil/trace.go:171","msg":"trace[134007923] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"117.156547ms","start":"2026-04-21T18:12:26.195484Z","end":"2026-04-21T18:12:26.312641Z","steps":["trace[134007923] 'process raft request' (duration: 39.314874ms)","trace[134007923] 'compare' (duration: 77.325493ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:12:26.930789Z","caller":"traceutil/trace.go:171","msg":"trace[520931242] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1388; }","duration":"146.10011ms","start":"2026-04-21T18:12:26.784656Z","end":"2026-04-21T18:12:26.930756Z","steps":["trace[520931242] 'read index received' (duration: 145.58353ms)","trace[520931242] 'applied index is now lower than readState.Index' (duration: 514.95µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:12:26.930885Z","caller":"traceutil/trace.go:171","msg":"trace[1719297157] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"163.459226ms","start":"2026-04-21T18:12:26.767408Z","end":"2026-04-21T18:12:26.930867Z","steps":["trace[1719297157] 'process raft request' (duration: 162.943175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:12:26.931127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.432767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-21T18:12:26.931199Z","caller":"traceutil/trace.go:171","msg":"trace[1538858021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1334; }","duration":"146.55479ms","start":"2026-04-21T18:12:26.784626Z","end":"2026-04-21T18:12:26.931181Z","steps":["trace[1538858021] 'agreement among raft nodes before linearized reading' (duration: 146.267584ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:12:27.352859Z","caller":"traceutil/trace.go:171","msg":"trace[423412104] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"112.078611ms","start":"2026-04-21T18:12:27.240745Z","end":"2026-04-21T18:12:27.352824Z","steps":["trace[423412104] 'process raft request' (duration: 111.815975ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:12:30.317006Z","caller":"traceutil/trace.go:171","msg":"trace[869626046] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"116.692545ms","start":"2026-04-21T18:12:30.200282Z","end":"2026-04-21T18:12:30.316974Z","steps":["trace[869626046] 'read index received' (duration: 116.44193ms)","trace[869626046] 'applied index is now lower than readState.Index' (duration: 249.515µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:12:30.317146Z","caller":"traceutil/trace.go:171","msg":"trace[1684409783] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"143.100473ms","start":"2026-04-21T18:12:30.173976Z","end":"2026-04-21T18:12:30.317077Z","steps":["trace[1684409783] 'process raft request' (duration: 142.792207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:12:30.317307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.003272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-04-21T18:12:30.317395Z","caller":"traceutil/trace.go:171","msg":"trace[1335644133] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1357; }","duration":"117.202376ms","start":"2026-04-21T18:12:30.200168Z","end":"2026-04-21T18:12:30.31737Z","steps":["trace[1335644133] 'agreement among raft nodes before linearized reading' (duration: 116.936731ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:12:38.00538Z","caller":"traceutil/trace.go:171","msg":"trace[328855350] linearizableReadLoop","detail":"{readStateIndex:1447; appliedIndex:1446; }","duration":"115.165856ms","start":"2026-04-21T18:12:37.890189Z","end":"2026-04-21T18:12:38.005355Z","steps":["trace[328855350] 'read index received' (duration: 115.037224ms)","trace[328855350] 'applied index is now lower than readState.Index' (duration: 127.532µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:12:38.005416Z","caller":"traceutil/trace.go:171","msg":"trace[1700896373] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"239.5951ms","start":"2026-04-21T18:12:37.765805Z","end":"2026-04-21T18:12:38.0054Z","steps":["trace[1700896373] 'process raft request' (duration: 239.404216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:12:38.005754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.556774ms","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-21T18:12:38.005794Z","caller":"traceutil/trace.go:171","msg":"trace[1841512403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1389; }","duration":"115.632106ms","start":"2026-04-21T18:12:37.89015Z","end":"2026-04-21T18:12:38.005782Z","steps":["trace[1841512403] 'agreement among raft nodes before linearized reading' (duration: 115.31876ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:04.686468Z","caller":"traceutil/trace.go:171","msg":"trace[568984769] linearizableReadLoop","detail":"{readStateIndex:1548; appliedIndex:1546; }","duration":"139.100222ms","start":"2026-04-21T18:13:04.547345Z","end":"2026-04-21T18:13:04.686445Z","steps":["trace[568984769] 'read index received' (duration: 138.371998ms)","trace[568984769] 'applied index is now lower than readState.Index' (duration: 727.214µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:13:04.686522Z","caller":"traceutil/trace.go:171","msg":"trace[1827689095] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"156.106447ms","start":"2026-04-21T18:13:04.53039Z","end":"2026-04-21T18:13:04.686497Z","steps":["trace[1827689095] 'process raft request' (duration: 155.403223ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:04.686572Z","caller":"traceutil/trace.go:171","msg":"trace[1607848080] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"146.026358ms","start":"2026-04-21T18:13:04.540521Z","end":"2026-04-21T18:13:04.686548Z","steps":["trace[1607848080] 'process raft request' (duration: 145.875615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:04.686655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.285266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-21T18:13:04.686697Z","caller":"traceutil/trace.go:171","msg":"trace[633649066] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1484; }","duration":"139.383568ms","start":"2026-04-21T18:13:04.547302Z","end":"2026-04-21T18:13:04.686686Z","steps":["trace[633649066] 'agreement among raft nodes before linearized reading' (duration: 139.287696ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:10.555986Z","caller":"traceutil/trace.go:171","msg":"trace[381091994] linearizableReadLoop","detail":"{readStateIndex:1566; appliedIndex:1565; }","duration":"267.601892ms","start":"2026-04-21T18:13:10.288368Z","end":"2026-04-21T18:13:10.55597Z","steps":["trace[381091994] 'read index received' (duration: 267.444099ms)","trace[381091994] 'applied index is now lower than readState.Index' (duration: 157.233µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:13:10.556093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.712544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:1 size:191"} {"level":"info","ts":"2026-04-21T18:13:10.556112Z","caller":"traceutil/trace.go:171","msg":"trace[272672115] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:1; response_revision:1501; }","duration":"267.784696ms","start":"2026-04-21T18:13:10.288321Z","end":"2026-04-21T18:13:10.556105Z","steps":["trace[272672115] 'agreement among raft nodes before linearized reading' (duration: 267.731735ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:10.556087Z","caller":"traceutil/trace.go:171","msg":"trace[79734879] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"299.323906ms","start":"2026-04-21T18:13:10.256723Z","end":"2026-04-21T18:13:10.556047Z","steps":["trace[79734879] 'process raft request' (duration: 299.146022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:13:10.556228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.40573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-04-21T18:13:10.556311Z","caller":"traceutil/trace.go:171","msg":"trace[977933114] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1501; }","duration":"237.508742ms","start":"2026-04-21T18:13:10.318782Z","end":"2026-04-21T18:13:10.556291Z","steps":["trace[977933114] 'agreement among raft nodes before linearized reading' (duration: 237.336658ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:13:12.687533Z","caller":"traceutil/trace.go:171","msg":"trace[1757117110] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"124.850107ms","start":"2026-04-21T18:13:12.562649Z","end":"2026-04-21T18:13:12.687499Z","steps":["trace[1757117110] 'process raft request' (duration: 124.650194ms)"],"step_count":1}