{"level":"warn","ts":"2026-04-02T21:23:58.656942Z","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-02T21:23:58.657046Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.86: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.86:2380","--initial-cluster=instance=https://199.19.213.86:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.86:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.86: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-02T21:23:58.657122Z","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-02T21:23:58.657132Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-04-02T21:23:58.657148Z","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-02T21:23:58.657769Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"]} {"level":"info","ts":"2026-04-02T21:23:58.657846Z","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.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.86: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-02T21:23:58.662132Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.077008ms"} {"level":"info","ts":"2026-04-02T21:23:58.678034Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"849a6ffb5e81c109","cluster-id":"d4d86b1dd8829199"} {"level":"info","ts":"2026-04-02T21:23:58.678139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T21:23:58.678164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 0"} {"level":"info","ts":"2026-04-02T21:23:58.678178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 849a6ffb5e81c109 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T21:23:58.678187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 1"} {"level":"info","ts":"2026-04-02T21:23:58.678227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"warn","ts":"2026-04-02T21:23:58.69122Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T21:23:58.69272Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T21:23:58.693957Z","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-02T21:23:58.696054Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"849a6ffb5e81c109","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T21:23:58.697706Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"849a6ffb5e81c109","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T21:23:58.700545Z","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-02T21:23:58.70094Z","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-02T21:23:58.700959Z","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-02T21:23:58.702352Z","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-02T21:23:58.702553Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"849a6ffb5e81c109","initial-advertise-peer-urls":["https://199.19.213.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T21:23:58.702577Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T21:23:58.702891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"info","ts":"2026-04-02T21:23:58.703044Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","added-peer-id":"849a6ffb5e81c109","added-peer-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-04-02T21:23:58.703304Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-04-02T21:23:58.703327Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-04-02T21:23:59.479329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T21:23:59.479498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T21:23:59.479634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgPreVoteResp from 849a6ffb5e81c109 at term 1"} {"level":"info","ts":"2026-04-02T21:23:59.47969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became candidate at term 2"} {"level":"info","ts":"2026-04-02T21:23:59.479704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgVoteResp from 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-04-02T21:23:59.479725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became leader at term 2"} {"level":"info","ts":"2026-04-02T21:23:59.479768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 849a6ffb5e81c109 elected leader 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-04-02T21:23:59.481182Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T21:23:59.482452Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T21:23:59.48249Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T21:23:59.482588Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T21:23:59.48303Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T21:23:59.48305Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T21:23:59.48324Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T21:23:59.483349Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T21:23:59.482436Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"849a6ffb5e81c109","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.86:2379]}","request-path":"/0/members/849a6ffb5e81c109/attributes","cluster-id":"d4d86b1dd8829199","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T21:23:59.485564Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.86:2379"} {"level":"info","ts":"2026-04-02T21:23:59.486846Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T21:24:10.981189Z","caller":"traceutil/trace.go:171","msg":"trace[1719451607] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"108.418113ms","start":"2026-04-02T21:24:10.872743Z","end":"2026-04-02T21:24:10.981162Z","steps":["trace[1719451607] 'process raft request' (duration: 107.885011ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:14.364199Z","caller":"traceutil/trace.go:171","msg":"trace[1553623301] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"158.548496ms","start":"2026-04-02T21:24:14.205619Z","end":"2026-04-02T21:24:14.364167Z","steps":["trace[1553623301] 'process raft request' (duration: 158.339061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:17.519201Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909821891383679395,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:17.76519Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.125554055s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T21:24:17.765596Z","caller":"traceutil/trace.go:171","msg":"trace[1131256092] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"747.16883ms","start":"2026-04-02T21:24:17.0184Z","end":"2026-04-02T21:24:17.765569Z","steps":["trace[1131256092] 'read index received' (duration: 746.994005ms)","trace[1131256092] 'applied index is now lower than readState.Index' (duration: 173.775µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:17.765658Z","caller":"traceutil/trace.go:171","msg":"trace[2022762233] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"1.12618599s","start":"2026-04-02T21:24:16.639441Z","end":"2026-04-02T21:24:17.765627Z","steps":["trace[2022762233] 'process raft request' (duration: 1.125994995s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:17.765873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.849512ms","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-02T21:24:17.765891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.450036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-02T21:24:17.765973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.399993ms","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-02T21:24:17.765983Z","caller":"traceutil/trace.go:171","msg":"trace[502660891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"168.022486ms","start":"2026-04-02T21:24:17.597943Z","end":"2026-04-02T21:24:17.765965Z","steps":["trace[502660891] 'agreement among raft nodes before linearized reading' (duration: 167.829011ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:17.76602Z","caller":"traceutil/trace.go:171","msg":"trace[55480442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:415; }","duration":"551.455594ms","start":"2026-04-02T21:24:17.214551Z","end":"2026-04-02T21:24:17.766007Z","steps":["trace[55480442] 'agreement among raft nodes before linearized reading' (duration: 551.335891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:17.766043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:17.214495Z","time spent":"551.543146ms","remote":"127.0.0.1:49792","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-02T21:24:17.766068Z","caller":"traceutil/trace.go:171","msg":"trace[2109596510] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:415; }","duration":"747.646551ms","start":"2026-04-02T21:24:17.018352Z","end":"2026-04-02T21:24:17.765998Z","steps":["trace[2109596510] 'agreement among raft nodes before linearized reading' (duration: 747.338103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:17.766129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:17.018333Z","time spent":"747.784815ms","remote":"127.0.0.1:49792","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-02T21:24:17.766178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:16.639406Z","time spent":"1.126321793s","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:24:20.026861Z","caller":"traceutil/trace.go:171","msg":"trace[1818253645] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"194.63861ms","start":"2026-04-02T21:24:19.832199Z","end":"2026-04-02T21:24:20.026837Z","steps":["trace[1818253645] 'process raft request' (duration: 194.588779ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:20.026982Z","caller":"traceutil/trace.go:171","msg":"trace[65614321] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"196.666298ms","start":"2026-04-02T21:24:19.830264Z","end":"2026-04-02T21:24:20.02693Z","steps":["trace[65614321] 'process raft request' (duration: 123.057337ms)","trace[65614321] 'compare' (duration: 73.277693ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:20.02705Z","caller":"traceutil/trace.go:171","msg":"trace[1680825352] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"196.661629ms","start":"2026-04-02T21:24:19.830346Z","end":"2026-04-02T21:24:20.027007Z","steps":["trace[1680825352] 'process raft request' (duration: 196.399183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:23.751741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.028944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:24:23.751851Z","caller":"traceutil/trace.go:171","msg":"trace[684965625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"825.201198ms","start":"2026-04-02T21:24:22.926619Z","end":"2026-04-02T21:24:23.75182Z","steps":["trace[684965625] 'range keys from in-memory index tree' (duration: 824.880811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:23.751898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:22.926592Z","time spent":"825.296111ms","remote":"127.0.0.1:49544","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-02T21:24:24.542225Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909821891383679439,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T21:24:24.721042Z","caller":"traceutil/trace.go:171","msg":"trace[1197809704] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:444; }","duration":"679.064061ms","start":"2026-04-02T21:24:24.041952Z","end":"2026-04-02T21:24:24.721016Z","steps":["trace[1197809704] 'read index received' (duration: 678.831796ms)","trace[1197809704] 'applied index is now lower than readState.Index' (duration: 231.595µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:24.721116Z","caller":"traceutil/trace.go:171","msg":"trace[1080009181] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"788.409517ms","start":"2026-04-02T21:24:23.932667Z","end":"2026-04-02T21:24:24.721076Z","steps":["trace[1080009181] 'process raft request' (duration: 788.196781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:24.721864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:23.932645Z","time spent":"789.116394ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:24:24.721909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.067987ms","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-02T21:24:24.72199Z","caller":"traceutil/trace.go:171","msg":"trace[1135045472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:431; }","duration":"678.20111ms","start":"2026-04-02T21:24:24.04377Z","end":"2026-04-02T21:24:24.721971Z","steps":["trace[1135045472] 'agreement among raft nodes before linearized reading' (duration: 678.058867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:24.72197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.19721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-02T21:24:24.722038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:24.043746Z","time spent":"678.279462ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T21:24:24.722082Z","caller":"traceutil/trace.go:171","msg":"trace[1683334663] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:431; }","duration":"678.353543ms","start":"2026-04-02T21:24:24.043706Z","end":"2026-04-02T21:24:24.722059Z","steps":["trace[1683334663] 'agreement among raft nodes before linearized reading' (duration: 678.088587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:24.721525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.553913ms","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-02T21:24:24.722137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:24.043695Z","time spent":"678.431065ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T21:24:24.722178Z","caller":"traceutil/trace.go:171","msg":"trace[1455743564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:431; }","duration":"680.27956ms","start":"2026-04-02T21:24:24.041884Z","end":"2026-04-02T21:24:24.722163Z","steps":["trace[1455743564] 'agreement among raft nodes before linearized reading' (duration: 679.45301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:24.72221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:24.041856Z","time spent":"680.344032ms","remote":"127.0.0.1:49792","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-02T21:24:25.428025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909821891383679442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:25.671839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.09844ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:24:25.672333Z","caller":"traceutil/trace.go:171","msg":"trace[2067723118] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:431; }","duration":"800.605203ms","start":"2026-04-02T21:24:24.871698Z","end":"2026-04-02T21:24:25.672303Z","steps":["trace[2067723118] 'range keys from in-memory index tree' (duration: 800.062739ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:25.676163Z","caller":"traceutil/trace.go:171","msg":"trace[1157761675] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"948.952762ms","start":"2026-04-02T21:24:24.727198Z","end":"2026-04-02T21:24:25.676151Z","steps":["trace[1157761675] 'process raft request' (duration: 948.837059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:25.676272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:24.727165Z","time spent":"949.039374ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:24:25.682202Z","caller":"traceutil/trace.go:171","msg":"trace[651448912] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:445; }","duration":"754.484807ms","start":"2026-04-02T21:24:24.927688Z","end":"2026-04-02T21:24:25.682173Z","steps":["trace[651448912] 'read index received' (duration: 748.41677ms)","trace[651448912] 'applied index is now lower than readState.Index' (duration: 6.067197ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:25.682269Z","caller":"traceutil/trace.go:171","msg":"trace[1070460185] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"954.692591ms","start":"2026-04-02T21:24:24.727527Z","end":"2026-04-02T21:24:25.68222Z","steps":["trace[1070460185] 'process raft request' (duration: 954.429884ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:25.682308Z","caller":"traceutil/trace.go:171","msg":"trace[741693995] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"954.323202ms","start":"2026-04-02T21:24:24.727941Z","end":"2026-04-02T21:24:25.682264Z","steps":["trace[741693995] 'process raft request' (duration: 954.167458ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:25.682398Z","caller":"traceutil/trace.go:171","msg":"trace[114709412] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"701.429763ms","start":"2026-04-02T21:24:24.980947Z","end":"2026-04-02T21:24:25.682376Z","steps":["trace[114709412] 'process raft request' (duration: 701.192997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:25.682478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:24.727499Z","time spent":"954.860605ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:24:25.68251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:24.980914Z","time spent":"701.539665ms","remote":"127.0.0.1:49792","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-02T21:24:25.682489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.765093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T21:24:25.682524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:24.727928Z","time spent":"954.482056ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:24:25.682569Z","caller":"traceutil/trace.go:171","msg":"trace[722432650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:435; }","duration":"754.926297ms","start":"2026-04-02T21:24:24.927629Z","end":"2026-04-02T21:24:25.682555Z","steps":["trace[722432650] 'agreement among raft nodes before linearized reading' (duration: 754.688911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:25.682613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:24.927603Z","time spent":"754.998699ms","remote":"127.0.0.1:49544","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-02T21:24:30.990698Z","caller":"traceutil/trace.go:171","msg":"trace[1205175711] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"236.566675ms","start":"2026-04-02T21:24:30.754106Z","end":"2026-04-02T21:24:30.990673Z","steps":["trace[1205175711] 'process raft request' (duration: 236.005871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:34.233637Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909821891383679534,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T21:24:34.396488Z","caller":"traceutil/trace.go:171","msg":"trace[396231961] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:497; }","duration":"662.932552ms","start":"2026-04-02T21:24:33.733524Z","end":"2026-04-02T21:24:34.396456Z","steps":["trace[396231961] 'read index received' (duration: 662.733917ms)","trace[396231961] 'applied index is now lower than readState.Index' (duration: 197.665µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:34.396567Z","caller":"traceutil/trace.go:171","msg":"trace[1673062536] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"663.296431ms","start":"2026-04-02T21:24:33.733259Z","end":"2026-04-02T21:24:34.396556Z","steps":["trace[1673062536] 'process raft request' (duration: 663.028654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:34.396664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.119796ms","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-02T21:24:34.39689Z","caller":"traceutil/trace.go:171","msg":"trace[1143011340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:481; }","duration":"663.217359ms","start":"2026-04-02T21:24:33.733466Z","end":"2026-04-02T21:24:34.396683Z","steps":["trace[1143011340] 'agreement among raft nodes before linearized reading' (duration: 663.126597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:34.396909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:33.733446Z","time spent":"663.458584ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T21:24:34.396926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:33.733244Z","time spent":"663.414703ms","remote":"127.0.0.1:49792","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-02T21:24:34.397054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.497486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T21:24:34.397079Z","caller":"traceutil/trace.go:171","msg":"trace[200335804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:481; }","duration":"656.574778ms","start":"2026-04-02T21:24:33.740499Z","end":"2026-04-02T21:24:34.397074Z","steps":["trace[200335804] 'agreement among raft nodes before linearized reading' (duration: 656.522427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:34.397096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:33.740479Z","time spent":"656.613719ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T21:24:34.533031Z","caller":"traceutil/trace.go:171","msg":"trace[1561159322] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"130.624091ms","start":"2026-04-02T21:24:34.402374Z","end":"2026-04-02T21:24:34.532998Z","steps":["trace[1561159322] 'process raft request' (duration: 118.320454ms)","trace[1561159322] 'compare' (duration: 12.184124ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:35.310339Z","caller":"traceutil/trace.go:171","msg":"trace[2066065630] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"284.838963ms","start":"2026-04-02T21:24:35.025451Z","end":"2026-04-02T21:24:35.31029Z","steps":["trace[2066065630] 'process raft request' (duration: 284.597947ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:36.515269Z","caller":"traceutil/trace.go:171","msg":"trace[360712074] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"108.68687ms","start":"2026-04-02T21:24:36.406501Z","end":"2026-04-02T21:24:36.515188Z","steps":["trace[360712074] 'process raft request' (duration: 108.498855ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:36.761303Z","caller":"traceutil/trace.go:171","msg":"trace[821112345] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:503; }","duration":"223.651801ms","start":"2026-04-02T21:24:36.537622Z","end":"2026-04-02T21:24:36.761274Z","steps":["trace[821112345] 'read index received' (duration: 220.6943ms)","trace[821112345] 'applied index is now lower than readState.Index' (duration: 2.956141ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:36.761599Z","caller":"traceutil/trace.go:171","msg":"trace[1046009675] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"242.484128ms","start":"2026-04-02T21:24:36.519095Z","end":"2026-04-02T21:24:36.761579Z","steps":["trace[1046009675] 'process raft request' (duration: 239.29169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:36.762058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.875047ms","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-02T21:24:36.762151Z","caller":"traceutil/trace.go:171","msg":"trace[415807189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:487; }","duration":"224.558453ms","start":"2026-04-02T21:24:36.537577Z","end":"2026-04-02T21:24:36.762136Z","steps":["trace[415807189] 'agreement among raft nodes before linearized reading' (duration: 223.797145ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:43.507421Z","caller":"traceutil/trace.go:171","msg":"trace[117232823] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"349.473282ms","start":"2026-04-02T21:24:43.157923Z","end":"2026-04-02T21:24:43.507397Z","steps":["trace[117232823] 'process raft request' (duration: 349.037131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:43.507591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:43.157903Z","time spent":"349.605705ms","remote":"127.0.0.1:49630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T21:24:43.525485Z","caller":"traceutil/trace.go:171","msg":"trace[814416634] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"363.65176ms","start":"2026-04-02T21:24:43.161781Z","end":"2026-04-02T21:24:43.525433Z","steps":["trace[814416634] 'process raft request' (duration: 363.404074ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:43.525684Z","caller":"traceutil/trace.go:171","msg":"trace[957710987] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"349.850431ms","start":"2026-04-02T21:24:43.175792Z","end":"2026-04-02T21:24:43.525642Z","steps":["trace[957710987] 'process raft request' (duration: 349.594245ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:43.525562Z","caller":"traceutil/trace.go:171","msg":"trace[1660677153] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"360.291157ms","start":"2026-04-02T21:24:43.165204Z","end":"2026-04-02T21:24:43.525495Z","steps":["trace[1660677153] 'process raft request' (duration: 360.105883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:43.525855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:43.175784Z","time spent":"349.987515ms","remote":"127.0.0.1:49630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T21:24:43.525872Z","caller":"traceutil/trace.go:171","msg":"trace[439442905] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"344.93317ms","start":"2026-04-02T21:24:43.180884Z","end":"2026-04-02T21:24:43.525817Z","steps":["trace[439442905] 'process raft request' (duration: 344.713965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:43.526159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.337063ms","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-02T21:24:43.526222Z","caller":"traceutil/trace.go:171","msg":"trace[365505334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:548; }","duration":"161.452956ms","start":"2026-04-02T21:24:43.36476Z","end":"2026-04-02T21:24:43.526213Z","steps":["trace[365505334] 'agreement among raft nodes before linearized reading' (duration: 161.288532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:43.525935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:43.161749Z","time spent":"363.852514ms","remote":"127.0.0.1:49630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T21:24:43.525958Z","caller":"traceutil/trace.go:171","msg":"trace[1031304968] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"350.045136ms","start":"2026-04-02T21:24:43.175695Z","end":"2026-04-02T21:24:43.52574Z","steps":["trace[1031304968] 'process raft request' (duration: 349.655657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:43.526565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:43.175665Z","time spent":"350.836285ms","remote":"127.0.0.1:49630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T21:24:43.525998Z","caller":"traceutil/trace.go:171","msg":"trace[1623783117] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"334.486575ms","start":"2026-04-02T21:24:43.191499Z","end":"2026-04-02T21:24:43.525986Z","steps":["trace[1623783117] 'process raft request' (duration: 334.196778ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:43.52601Z","caller":"traceutil/trace.go:171","msg":"trace[899650759] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"318.111774ms","start":"2026-04-02T21:24:43.207888Z","end":"2026-04-02T21:24:43.526Z","steps":["trace[899650759] 'process raft request' (duration: 317.857928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:43.526926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:43.191449Z","time spent":"335.407168ms","remote":"127.0.0.1:49630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T21:24:43.525987Z","caller":"traceutil/trace.go:171","msg":"trace[1632299720] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:555; }","duration":"161.145628ms","start":"2026-04-02T21:24:43.364795Z","end":"2026-04-02T21:24:43.52594Z","steps":["trace[1632299720] 'read index received' (duration: 142.44627ms)","trace[1632299720] 'applied index is now lower than readState.Index' (duration: 18.698138ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:43.52603Z","caller":"traceutil/trace.go:171","msg":"trace[489924838] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"342.518841ms","start":"2026-04-02T21:24:43.183502Z","end":"2026-04-02T21:24:43.526021Z","steps":["trace[489924838] 'process raft request' (duration: 342.151882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:43.527066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:43.207851Z","time spent":"319.110298ms","remote":"127.0.0.1:49630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T21:24:43.527172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:43.183467Z","time spent":"343.66553ms","remote":"127.0.0.1:49630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T21:24:43.526024Z","caller":"traceutil/trace.go:171","msg":"trace[1454234472] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"163.27282ms","start":"2026-04-02T21:24:43.362739Z","end":"2026-04-02T21:24:43.526012Z","steps":["trace[1454234472] 'process raft request' (duration: 163.112566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:43.526034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:43.165168Z","time spent":"360.748008ms","remote":"127.0.0.1:49630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T21:24:43.52606Z","caller":"traceutil/trace.go:171","msg":"trace[1141384461] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"349.698158ms","start":"2026-04-02T21:24:43.176342Z","end":"2026-04-02T21:24:43.52604Z","steps":["trace[1141384461] 'process raft request' (duration: 349.138644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:43.527412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:43.176311Z","time spent":"351.073142ms","remote":"127.0.0.1:49630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T21:24:43.526068Z","caller":"traceutil/trace.go:171","msg":"trace[136718140] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"348.651702ms","start":"2026-04-02T21:24:43.177407Z","end":"2026-04-02T21:24:43.526059Z","steps":["trace[136718140] 'process raft request' (duration: 348.14376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:43.527529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:43.177395Z","time spent":"350.067897ms","remote":"127.0.0.1:49630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T21:24:43.526281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:43.180857Z","time spent":"345.274399ms","remote":"127.0.0.1:49630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T21:24:44.484272Z","caller":"traceutil/trace.go:171","msg":"trace[1707368938] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"296.133406ms","start":"2026-04-02T21:24:44.188095Z","end":"2026-04-02T21:24:44.484229Z","steps":["trace[1707368938] 'process raft request' (duration: 294.147787ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:44.49469Z","caller":"traceutil/trace.go:171","msg":"trace[400737651] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"143.102526ms","start":"2026-04-02T21:24:44.350305Z","end":"2026-04-02T21:24:44.493408Z","steps":["trace[400737651] 'process raft request' (duration: 142.747247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:45.877388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.057044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570105"} {"level":"info","ts":"2026-04-02T21:24:45.877486Z","caller":"traceutil/trace.go:171","msg":"trace[517450024] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:615; }","duration":"574.213007ms","start":"2026-04-02T21:24:45.30325Z","end":"2026-04-02T21:24:45.877464Z","steps":["trace[517450024] 'range keys from in-memory index tree' (duration: 573.743495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:45.877525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:45.303227Z","time spent":"574.287849ms","remote":"127.0.0.1:49630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":570129,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-04-02T21:24:45.878009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.912892ms","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-02T21:24:45.87812Z","caller":"traceutil/trace.go:171","msg":"trace[880070698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"323.089166ms","start":"2026-04-02T21:24:45.555005Z","end":"2026-04-02T21:24:45.878095Z","steps":["trace[880070698] 'range keys from in-memory index tree' (duration: 322.668495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:45.878167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:45.554973Z","time spent":"323.181738ms","remote":"127.0.0.1:49792","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-02T21:24:46.220663Z","caller":"traceutil/trace.go:171","msg":"trace[296585656] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"291.743348ms","start":"2026-04-02T21:24:45.928858Z","end":"2026-04-02T21:24:46.220601Z","steps":["trace[296585656] 'read index received' (duration: 291.43238ms)","trace[296585656] 'applied index is now lower than readState.Index' (duration: 309.388µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:46.220853Z","caller":"traceutil/trace.go:171","msg":"trace[524236107] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"338.116055ms","start":"2026-04-02T21:24:45.88269Z","end":"2026-04-02T21:24:46.220806Z","steps":["trace[524236107] 'process raft request' (duration: 337.681424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:46.220969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.039475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:24:46.221033Z","caller":"traceutil/trace.go:171","msg":"trace[1850761217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"292.211ms","start":"2026-04-02T21:24:45.928803Z","end":"2026-04-02T21:24:46.221014Z","steps":["trace[1850761217] 'agreement among raft nodes before linearized reading' (duration: 292.007105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:46.221023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.536199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"warn","ts":"2026-04-02T21:24:46.221058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:45.882654Z","time spent":"338.301638ms","remote":"127.0.0.1:49792","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-02T21:24:46.22108Z","caller":"traceutil/trace.go:171","msg":"trace[1403360873] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:616; }","duration":"228.648602ms","start":"2026-04-02T21:24:45.992414Z","end":"2026-04-02T21:24:46.221063Z","steps":["trace[1403360873] 'agreement among raft nodes before linearized reading' (duration: 228.406036ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:51.679955Z","caller":"traceutil/trace.go:171","msg":"trace[1837601623] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"206.531941ms","start":"2026-04-02T21:24:51.473363Z","end":"2026-04-02T21:24:51.679895Z","steps":["trace[1837601623] 'process raft request' (duration: 205.69371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:52.429506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909821891383679837,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T21:24:52.631473Z","caller":"traceutil/trace.go:171","msg":"trace[599725846] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"922.761709ms","start":"2026-04-02T21:24:51.708683Z","end":"2026-04-02T21:24:52.631445Z","steps":["trace[599725846] 'process raft request' (duration: 922.613875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:52.631613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:51.708653Z","time spent":"922.892002ms","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:24:53.929114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000551735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-02T21:24:53.92927Z","caller":"traceutil/trace.go:171","msg":"trace[1797461978] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00076306s","start":"2026-04-02T21:24:51.928476Z","end":"2026-04-02T21:24:53.929239Z","steps":["trace[1797461978] 'agreement among raft nodes before linearized reading' (duration: 2.000574185s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:53.929346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:51.928444Z","time spent":"2.000884823s","remote":"127.0.0.1:49544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/02 21:24:53 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-02T21:24:53.936728Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.305389292s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-02T21:24:54.850447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.310187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T21:24:54.851696Z","caller":"traceutil/trace.go:171","msg":"trace[1573723068] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"2.953123054s","start":"2026-04-02T21:24:51.898533Z","end":"2026-04-02T21:24:54.851656Z","steps":["trace[1573723068] 'process raft request' (duration: 2.952025107s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:54.851969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:51.898509Z","time spent":"2.953336919s","remote":"127.0.0.1:49792","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-02T21:24:54.852199Z","caller":"traceutil/trace.go:171","msg":"trace[117633477] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"3.114247621s","start":"2026-04-02T21:24:51.737787Z","end":"2026-04-02T21:24:54.852034Z","steps":["trace[117633477] 'process raft request' (duration: 2.199093869s)","trace[117633477] 'compare' (duration: 913.100892ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:24:54.852326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:51.737732Z","time spent":"3.114531229s","remote":"127.0.0.1:50024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T21:24:54.851721Z","caller":"traceutil/trace.go:171","msg":"trace[345570015] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:670; }","duration":"2.922180325s","start":"2026-04-02T21:24:51.928509Z","end":"2026-04-02T21:24:54.85069Z","steps":["trace[345570015] 'read index received' (duration: 702.856161ms)","trace[345570015] 'applied index is now lower than readState.Index' (duration: 2.219322354s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:24:54.852989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.600164385s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-02T21:24:54.853008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.910003617s","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-02T21:24:54.853033Z","caller":"traceutil/trace.go:171","msg":"trace[1435745729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"2.600243267s","start":"2026-04-02T21:24:52.252767Z","end":"2026-04-02T21:24:54.85301Z","steps":["trace[1435745729] 'agreement among raft nodes before linearized reading' (duration: 2.600108314s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:54.85306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:52.252745Z","time spent":"2.600308819s","remote":"127.0.0.1:49792","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-02T21:24:54.85307Z","caller":"traceutil/trace.go:171","msg":"trace[897295241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"1.910089469s","start":"2026-04-02T21:24:52.94295Z","end":"2026-04-02T21:24:54.85304Z","steps":["trace[897295241] 'agreement among raft nodes before linearized reading' (duration: 1.909934655s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:54.853099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:52.942936Z","time spent":"1.910157151s","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T21:24:54.853206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.612449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:24:54.853235Z","caller":"traceutil/trace.go:171","msg":"trace[1440658538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"923.66065ms","start":"2026-04-02T21:24:53.929557Z","end":"2026-04-02T21:24:54.853218Z","steps":["trace[1440658538] 'agreement among raft nodes before linearized reading' (duration: 923.627069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:54.853253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:53.929536Z","time spent":"923.711592ms","remote":"127.0.0.1:49560","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-02T21:24:54.853286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.912345755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T21:24:54.853321Z","caller":"traceutil/trace.go:171","msg":"trace[26792420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"1.912415356s","start":"2026-04-02T21:24:52.940887Z","end":"2026-04-02T21:24:54.853302Z","steps":["trace[26792420] 'agreement among raft nodes before linearized reading' (duration: 1.912340934s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:54.853341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:52.940858Z","time spent":"1.912476717s","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T21:24:54.853626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.149242979s","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-02T21:24:54.853684Z","caller":"traceutil/trace.go:171","msg":"trace[1188445829] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"2.149326641s","start":"2026-04-02T21:24:52.704333Z","end":"2026-04-02T21:24:54.85366Z","steps":["trace[1188445829] 'agreement among raft nodes before linearized reading' (duration: 2.149174967s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:54.853718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:52.704304Z","time spent":"2.149404292s","remote":"127.0.0.1:49792","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-02T21:24:54.854466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.073168714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-02T21:24:54.854551Z","caller":"traceutil/trace.go:171","msg":"trace[242884547] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:649; }","duration":"2.073312988s","start":"2026-04-02T21:24:52.781217Z","end":"2026-04-02T21:24:54.85453Z","steps":["trace[242884547] 'agreement among raft nodes before linearized reading' (duration: 2.072223962s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:54.854602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:52.781186Z","time spent":"2.07340434s","remote":"127.0.0.1:49606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.86\" "} {"level":"warn","ts":"2026-04-02T21:24:56.426636Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909821891383679866,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:24:56.570165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.22206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T21:24:56.570353Z","caller":"traceutil/trace.go:171","msg":"trace[117947560] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"933.713105ms","start":"2026-04-02T21:24:55.636615Z","end":"2026-04-02T21:24:56.570328Z","steps":["trace[117947560] 'process raft request' (duration: 276.226508ms)","trace[117947560] 'compare' (duration: 657.002425ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:24:56.570615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:55.636378Z","time spent":"934.023382ms","remote":"127.0.0.1:49652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T21:24:56.570914Z","caller":"traceutil/trace.go:171","msg":"trace[898368615] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"644.55662ms","start":"2026-04-02T21:24:55.92633Z","end":"2026-04-02T21:24:56.570887Z","steps":["trace[898368615] 'read index received' (duration: 644.339904ms)","trace[898368615] 'applied index is now lower than readState.Index' (duration: 215.826µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:56.570995Z","caller":"traceutil/trace.go:171","msg":"trace[1286756818] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"920.850859ms","start":"2026-04-02T21:24:55.650102Z","end":"2026-04-02T21:24:56.570953Z","steps":["trace[1286756818] 'process raft request' (duration: 920.620864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:56.571072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.704594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:24:56.571109Z","caller":"traceutil/trace.go:171","msg":"trace[1349906400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"644.811467ms","start":"2026-04-02T21:24:55.926285Z","end":"2026-04-02T21:24:56.571096Z","steps":["trace[1349906400] 'agreement among raft nodes before linearized reading' (duration: 644.708904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:56.57114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:55.926268Z","time spent":"644.863008ms","remote":"127.0.0.1:49544","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-02T21:24:56.571153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:55.650062Z","time spent":"920.993003ms","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17228,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:24:56.632212Z","caller":"traceutil/trace.go:171","msg":"trace[521768649] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"192.620679ms","start":"2026-04-02T21:24:56.439551Z","end":"2026-04-02T21:24:56.632172Z","steps":["trace[521768649] 'process raft request' (duration: 192.361063ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:59.256088Z","caller":"traceutil/trace.go:171","msg":"trace[1575064110] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"278.188736ms","start":"2026-04-02T21:24:58.97784Z","end":"2026-04-02T21:24:59.256028Z","steps":["trace[1575064110] 'read index received' (duration: 277.853297ms)","trace[1575064110] 'applied index is now lower than readState.Index' (duration: 333.749µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:59.256269Z","caller":"traceutil/trace.go:171","msg":"trace[1820060570] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"404.93627ms","start":"2026-04-02T21:24:58.851205Z","end":"2026-04-02T21:24:59.256141Z","steps":["trace[1820060570] 'process raft request' (duration: 404.5309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:59.256372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.515373ms","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-02T21:24:59.25647Z","caller":"traceutil/trace.go:171","msg":"trace[1057678401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:691; }","duration":"278.626516ms","start":"2026-04-02T21:24:58.977822Z","end":"2026-04-02T21:24:59.256449Z","steps":["trace[1057678401] 'agreement among raft nodes before linearized reading' (duration: 278.338539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:59.256488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:58.851168Z","time spent":"405.208057ms","remote":"127.0.0.1:49630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T21:24:59.266056Z","caller":"traceutil/trace.go:171","msg":"trace[1998987574] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"215.086619ms","start":"2026-04-02T21:24:59.050933Z","end":"2026-04-02T21:24:59.26602Z","steps":["trace[1998987574] 'process raft request' (duration: 214.885764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:59.266121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.441621ms","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-02T21:24:59.266216Z","caller":"traceutil/trace.go:171","msg":"trace[156910744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:692; }","duration":"249.592065ms","start":"2026-04-02T21:24:59.016605Z","end":"2026-04-02T21:24:59.266197Z","steps":["trace[156910744] 'agreement among raft nodes before linearized reading' (duration: 249.337728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:59.266318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.865299ms","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-02T21:24:59.266306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.379053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T21:24:59.266354Z","caller":"traceutil/trace.go:171","msg":"trace[913381379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:692; }","duration":"184.94621ms","start":"2026-04-02T21:24:59.081396Z","end":"2026-04-02T21:24:59.266342Z","steps":["trace[913381379] 'agreement among raft nodes before linearized reading' (duration: 184.811567ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:59.26638Z","caller":"traceutil/trace.go:171","msg":"trace[1410325259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:692; }","duration":"183.535997ms","start":"2026-04-02T21:24:59.082826Z","end":"2026-04-02T21:24:59.266362Z","steps":["trace[1410325259] 'agreement among raft nodes before linearized reading' (duration: 183.231219ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:25:13.046181Z","caller":"traceutil/trace.go:171","msg":"trace[790754471] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:845; }","duration":"261.707362ms","start":"2026-04-02T21:25:12.784427Z","end":"2026-04-02T21:25:13.046135Z","steps":["trace[790754471] 'read index received' (duration: 32.65463ms)","trace[790754471] 'applied index is now lower than readState.Index' (duration: 229.051432ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:25:13.046744Z","caller":"traceutil/trace.go:171","msg":"trace[1856404457] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"289.291027ms","start":"2026-04-02T21:25:12.757404Z","end":"2026-04-02T21:25:13.046695Z","steps":["trace[1856404457] 'process raft request' (duration: 288.527209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:25:13.047601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.781029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:25:13.047679Z","caller":"traceutil/trace.go:171","msg":"trace[590988145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:816; }","duration":"120.894002ms","start":"2026-04-02T21:25:12.926747Z","end":"2026-04-02T21:25:13.047641Z","steps":["trace[590988145] 'agreement among raft nodes before linearized reading' (duration: 120.757328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:25:13.050506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.066349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-02T21:25:13.050808Z","caller":"traceutil/trace.go:171","msg":"trace[1577028692] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:816; }","duration":"266.221653ms","start":"2026-04-02T21:25:12.784375Z","end":"2026-04-02T21:25:13.050596Z","steps":["trace[1577028692] 'agreement among raft nodes before linearized reading' (duration: 262.323978ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:25:49.533919Z","caller":"traceutil/trace.go:171","msg":"trace[763544250] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"525.722877ms","start":"2026-04-02T21:25:49.008169Z","end":"2026-04-02T21:25:49.533892Z","steps":["trace[763544250] 'process raft request' (duration: 525.569333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:25:49.534082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:25:49.008148Z","time spent":"525.8591ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:25:50.297736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.701258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T21:25:50.297851Z","caller":"traceutil/trace.go:171","msg":"trace[1776753947] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"734.622434ms","start":"2026-04-02T21:25:49.563216Z","end":"2026-04-02T21:25:50.297839Z","steps":["trace[1776753947] 'read index received' (duration: 260.66153ms)","trace[1776753947] 'applied index is now lower than readState.Index' (duration: 473.959914ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:25:50.297928Z","caller":"traceutil/trace.go:171","msg":"trace[20163217] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"741.223105ms","start":"2026-04-02T21:25:49.556673Z","end":"2026-04-02T21:25:50.297897Z","steps":["trace[20163217] 'process raft request' (duration: 267.295792ms)","trace[20163217] 'compare' (duration: 472.807426ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:25:50.297988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.772118ms","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-02T21:25:50.298016Z","caller":"traceutil/trace.go:171","msg":"trace[235528137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1041; }","duration":"734.834769ms","start":"2026-04-02T21:25:49.563174Z","end":"2026-04-02T21:25:50.298008Z","steps":["trace[235528137] 'agreement among raft nodes before linearized reading' (duration: 734.715796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:25:50.298035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:25:49.556657Z","time spent":"741.323678ms","remote":"127.0.0.1:49792","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-02T21:25:50.298046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:25:49.563154Z","time spent":"734.88473ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T21:25:50.798209Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909821891383680728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:25:51.140805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.375526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T21:25:51.140984Z","caller":"traceutil/trace.go:171","msg":"trace[691501752] linearizableReadLoop","detail":"{readStateIndex:1083; appliedIndex:1081; }","duration":"843.099137ms","start":"2026-04-02T21:25:50.297869Z","end":"2026-04-02T21:25:51.140968Z","steps":["trace[691501752] 'read index received' (duration: 634.482776ms)","trace[691501752] 'applied index is now lower than readState.Index' (duration: 208.615111ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:25:51.141105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.577844164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-02T21:25:51.141082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2078152s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:25:51.141132Z","caller":"traceutil/trace.go:171","msg":"trace[1962599360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1043; }","duration":"1.577870665s","start":"2026-04-02T21:25:49.563253Z","end":"2026-04-02T21:25:51.141123Z","steps":["trace[1962599360] 'agreement among raft nodes before linearized reading' (duration: 1.577761292s)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:25:51.141163Z","caller":"traceutil/trace.go:171","msg":"trace[1581713721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1043; }","duration":"1.207930052s","start":"2026-04-02T21:25:49.933215Z","end":"2026-04-02T21:25:51.141145Z","steps":["trace[1581713721] 'agreement among raft nodes before linearized reading' (duration: 1.207765558s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:25:51.141202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:25:49.9332Z","time spent":"1.207993435s","remote":"127.0.0.1:49544","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-02T21:25:51.141195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.523380927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:25:51.141235Z","caller":"traceutil/trace.go:171","msg":"trace[225988810] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1043; }","duration":"1.523455408s","start":"2026-04-02T21:25:49.61777Z","end":"2026-04-02T21:25:51.141225Z","steps":["trace[225988810] 'agreement among raft nodes before linearized reading' (duration: 1.523381207s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:25:51.141236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.577950227s","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-02T21:25:51.141257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:25:49.617754Z","time spent":"1.52349735s","remote":"127.0.0.1:49712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-04-02T21:25:51.141266Z","caller":"traceutil/trace.go:171","msg":"trace[1895131484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1043; }","duration":"1.577999467s","start":"2026-04-02T21:25:49.563259Z","end":"2026-04-02T21:25:51.141259Z","steps":["trace[1895131484] 'agreement among raft nodes before linearized reading' (duration: 1.577925615s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:25:51.14129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:25:49.563246Z","time spent":"1.578038939s","remote":"127.0.0.1:49792","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-02T21:25:51.141165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:25:49.563211Z","time spent":"1.577945487s","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T21:25:51.141381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.493014991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T21:25:51.1414Z","caller":"traceutil/trace.go:171","msg":"trace[1185490284] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1043; }","duration":"1.493061933s","start":"2026-04-02T21:25:49.648332Z","end":"2026-04-02T21:25:51.141393Z","steps":["trace[1185490284] 'agreement among raft nodes before linearized reading' (duration: 1.493020521s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:25:51.141417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:25:49.648316Z","time spent":"1.493096893s","remote":"127.0.0.1:49672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-04-02T21:25:51.141425Z","caller":"traceutil/trace.go:171","msg":"trace[1982741294] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"838.590406ms","start":"2026-04-02T21:25:50.302797Z","end":"2026-04-02T21:25:51.141387Z","steps":["trace[1982741294] 'process raft request' (duration: 838.121905ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:25:51.141479Z","caller":"traceutil/trace.go:171","msg":"trace[1255453128] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"1.101785168s","start":"2026-04-02T21:25:50.039655Z","end":"2026-04-02T21:25:51.14144Z","steps":["trace[1255453128] 'process raft request' (duration: 892.698565ms)","trace[1255453128] 'compare' (duration: 208.281483ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:25:51.141573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:25:50.30278Z","time spent":"838.71966ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:25:51.141618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:25:50.039636Z","time spent":"1.101909081s","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:26:12.905179Z","caller":"traceutil/trace.go:171","msg":"trace[2116368228] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1156; }","duration":"244.314039ms","start":"2026-04-02T21:26:12.660835Z","end":"2026-04-02T21:26:12.905149Z","steps":["trace[2116368228] 'read index received' (duration: 244.113094ms)","trace[2116368228] 'applied index is now lower than readState.Index' (duration: 200.215µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:26:12.90523Z","caller":"traceutil/trace.go:171","msg":"trace[739828221] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"503.006368ms","start":"2026-04-02T21:26:12.402174Z","end":"2026-04-02T21:26:12.90518Z","steps":["trace[739828221] 'process raft request' (duration: 502.819494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:12.905352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:12.402153Z","time spent":"503.135762ms","remote":"127.0.0.1:49792","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-02T21:26:12.905679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.465712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T21:26:12.90574Z","caller":"traceutil/trace.go:171","msg":"trace[1170973785] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1113; }","duration":"244.926063ms","start":"2026-04-02T21:26:12.660803Z","end":"2026-04-02T21:26:12.905729Z","steps":["trace[1170973785] 'agreement among raft nodes before linearized reading' (duration: 244.466132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:15.206199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.783705ms","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-02T21:26:15.206267Z","caller":"traceutil/trace.go:171","msg":"trace[731885471] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1119; }","duration":"296.896088ms","start":"2026-04-02T21:26:14.909357Z","end":"2026-04-02T21:26:15.206253Z","steps":["trace[731885471] 'range keys from in-memory index tree' (duration: 296.622641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:15.206516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.625059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:26:15.206647Z","caller":"traceutil/trace.go:171","msg":"trace[1081486510] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1119; }","duration":"711.809133ms","start":"2026-04-02T21:26:14.494811Z","end":"2026-04-02T21:26:15.20662Z","steps":["trace[1081486510] 'count revisions from in-memory index tree' (duration: 711.484696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:15.206692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:14.494794Z","time spent":"711.886475ms","remote":"127.0.0.1:57274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-02T21:26:15.206797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157222598s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:26:15.206863Z","caller":"traceutil/trace.go:171","msg":"trace[913802722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1119; }","duration":"1.157342931s","start":"2026-04-02T21:26:14.049505Z","end":"2026-04-02T21:26:15.206848Z","steps":["trace[913802722] 'range keys from in-memory index tree' (duration: 1.157151877s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:15.206893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:14.049489Z","time spent":"1.157395692s","remote":"127.0.0.1:49544","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-02T21:26:15.206977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.346246ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:26:15.207031Z","caller":"traceutil/trace.go:171","msg":"trace[2124982707] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1119; }","duration":"336.403938ms","start":"2026-04-02T21:26:14.87062Z","end":"2026-04-02T21:26:15.207024Z","steps":["trace[2124982707] 'range keys from in-memory index tree' (duration: 336.338506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:15.207195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.232288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:26:15.207225Z","caller":"traceutil/trace.go:171","msg":"trace[203428156] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1119; }","duration":"381.30859ms","start":"2026-04-02T21:26:14.825909Z","end":"2026-04-02T21:26:15.207218Z","steps":["trace[203428156] 'count revisions from in-memory index tree' (duration: 381.161837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:15.207249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:14.825883Z","time spent":"381.358941ms","remote":"127.0.0.1:48700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-04-02T21:26:15.339361Z","caller":"traceutil/trace.go:171","msg":"trace[1462585324] linearizableReadLoop","detail":"{readStateIndex:1165; appliedIndex:1164; }","duration":"122.995309ms","start":"2026-04-02T21:26:15.216339Z","end":"2026-04-02T21:26:15.339334Z","steps":["trace[1462585324] 'read index received' (duration: 122.925697ms)","trace[1462585324] 'applied index is now lower than readState.Index' (duration: 68.462µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:26:15.339441Z","caller":"traceutil/trace.go:171","msg":"trace[109616387] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"128.368741ms","start":"2026-04-02T21:26:15.211049Z","end":"2026-04-02T21:26:15.339417Z","steps":["trace[109616387] 'process raft request' (duration: 128.135555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:15.33954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.182374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:26:15.339588Z","caller":"traceutil/trace.go:171","msg":"trace[1308597635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1120; }","duration":"123.278187ms","start":"2026-04-02T21:26:15.216296Z","end":"2026-04-02T21:26:15.339574Z","steps":["trace[1308597635] 'agreement among raft nodes before linearized reading' (duration: 123.181724ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:16.550693Z","caller":"traceutil/trace.go:171","msg":"trace[188802618] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"187.170836ms","start":"2026-04-02T21:26:16.363502Z","end":"2026-04-02T21:26:16.550673Z","steps":["trace[188802618] 'process raft request' (duration: 187.067143ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:17.914919Z","caller":"traceutil/trace.go:171","msg":"trace[995584646] linearizableReadLoop","detail":"{readStateIndex:1176; appliedIndex:1174; }","duration":"119.950194ms","start":"2026-04-02T21:26:17.794945Z","end":"2026-04-02T21:26:17.914895Z","steps":["trace[995584646] 'read index received' (duration: 119.711528ms)","trace[995584646] 'applied index is now lower than readState.Index' (duration: 237.656µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:26:17.915111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.17533ms","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-02T21:26:17.915152Z","caller":"traceutil/trace.go:171","msg":"trace[1477699843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1131; }","duration":"120.267962ms","start":"2026-04-02T21:26:17.794873Z","end":"2026-04-02T21:26:17.915141Z","steps":["trace[1477699843] 'agreement among raft nodes before linearized reading' (duration: 120.121059ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:17.915108Z","caller":"traceutil/trace.go:171","msg":"trace[1913168311] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"444.227736ms","start":"2026-04-02T21:26:17.470721Z","end":"2026-04-02T21:26:17.914948Z","steps":["trace[1913168311] 'process raft request' (duration: 443.924788ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:17.915299Z","caller":"traceutil/trace.go:171","msg":"trace[370460689] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"443.127258ms","start":"2026-04-02T21:26:17.472138Z","end":"2026-04-02T21:26:17.915265Z","steps":["trace[370460689] 'process raft request' (duration: 442.696068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:17.915488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:17.470701Z","time spent":"444.630695ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:26:17.915503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:17.472122Z","time spent":"443.271092ms","remote":"127.0.0.1:49792","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-02T21:26:18.021885Z","caller":"traceutil/trace.go:171","msg":"trace[1434786119] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"102.695751ms","start":"2026-04-02T21:26:17.919157Z","end":"2026-04-02T21:26:18.021853Z","steps":["trace[1434786119] 'process raft request' (duration: 102.512836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:20.007705Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909821891383680971,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T21:26:20.008052Z","caller":"traceutil/trace.go:171","msg":"trace[1261471780] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"616.33433ms","start":"2026-04-02T21:26:19.391691Z","end":"2026-04-02T21:26:20.008026Z","steps":["trace[1261471780] 'process raft request' (duration: 616.109344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:20.008191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:19.391671Z","time spent":"616.445513ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:26:20.0682Z","caller":"traceutil/trace.go:171","msg":"trace[1624776923] linearizableReadLoop","detail":"{readStateIndex:1181; appliedIndex:1179; }","duration":"560.580131ms","start":"2026-04-02T21:26:19.507595Z","end":"2026-04-02T21:26:20.068175Z","steps":["trace[1624776923] 'read index received' (duration: 500.2182ms)","trace[1624776923] 'applied index is now lower than readState.Index' (duration: 60.360811ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:26:20.068392Z","caller":"etcdserver/v3_server.go:874","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":13909821891383680974,"received-request-id":13909821891383680971} {"level":"warn","ts":"2026-04-02T21:26:20.0686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.996781ms","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-02T21:26:20.068678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.344558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T21:26:20.068694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.738244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:26:20.068767Z","caller":"traceutil/trace.go:171","msg":"trace[1601876027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1135; }","duration":"142.838967ms","start":"2026-04-02T21:26:19.925914Z","end":"2026-04-02T21:26:20.068753Z","steps":["trace[1601876027] 'agreement among raft nodes before linearized reading' (duration: 142.722094ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:20.068747Z","caller":"traceutil/trace.go:171","msg":"trace[300745508] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1135; }","duration":"244.499642ms","start":"2026-04-02T21:26:19.824233Z","end":"2026-04-02T21:26:20.068733Z","steps":["trace[300745508] 'agreement among raft nodes before linearized reading' (duration: 244.352778ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:20.068688Z","caller":"traceutil/trace.go:171","msg":"trace[710996494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1135; }","duration":"561.130075ms","start":"2026-04-02T21:26:19.507549Z","end":"2026-04-02T21:26:20.068679Z","steps":["trace[710996494] 'agreement among raft nodes before linearized reading' (duration: 560.786956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:20.068945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:19.507531Z","time spent":"561.403042ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T21:26:20.068436Z","caller":"traceutil/trace.go:171","msg":"trace[604084753] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"595.570821ms","start":"2026-04-02T21:26:19.472734Z","end":"2026-04-02T21:26:20.068305Z","steps":["trace[604084753] 'process raft request' (duration: 595.307934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:20.069076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:19.472702Z","time spent":"596.327699ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:26:20.069199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.014237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T21:26:20.069224Z","caller":"traceutil/trace.go:171","msg":"trace[1859743122] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1135; }","duration":"149.054829ms","start":"2026-04-02T21:26:19.920163Z","end":"2026-04-02T21:26:20.069218Z","steps":["trace[1859743122] 'agreement among raft nodes before linearized reading' (duration: 148.965807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:20.069284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.968721ms","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-02T21:26:20.069323Z","caller":"traceutil/trace.go:171","msg":"trace[356227010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1135; }","duration":"150.037303ms","start":"2026-04-02T21:26:19.919275Z","end":"2026-04-02T21:26:20.069312Z","steps":["trace[356227010] 'agreement among raft nodes before linearized reading' (duration: 149.929471ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:20.47269Z","caller":"traceutil/trace.go:171","msg":"trace[1229526964] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"398.714048ms","start":"2026-04-02T21:26:20.073954Z","end":"2026-04-02T21:26:20.472668Z","steps":["trace[1229526964] 'process raft request' (duration: 398.528774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:20.472821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:20.073936Z","time spent":"398.819741ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:26:20.49065Z","caller":"traceutil/trace.go:171","msg":"trace[1753667479] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"416.28583ms","start":"2026-04-02T21:26:20.074328Z","end":"2026-04-02T21:26:20.490613Z","steps":["trace[1753667479] 'process raft request' (duration: 415.913561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:20.490865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:20.074315Z","time spent":"416.425493ms","remote":"127.0.0.1:49792","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-02T21:26:20.491245Z","caller":"traceutil/trace.go:171","msg":"trace[1545356203] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"416.815193ms","start":"2026-04-02T21:26:20.074384Z","end":"2026-04-02T21:26:20.491199Z","steps":["trace[1545356203] 'process raft request' (duration: 416.521575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:20.491438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.362094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:26:20.49129Z","caller":"traceutil/trace.go:171","msg":"trace[253981011] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"416.309401ms","start":"2026-04-02T21:26:20.07497Z","end":"2026-04-02T21:26:20.49128Z","steps":["trace[253981011] 'process raft request' (duration: 416.092965ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:20.491475Z","caller":"traceutil/trace.go:171","msg":"trace[312851075] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1139; }","duration":"199.454536ms","start":"2026-04-02T21:26:20.292011Z","end":"2026-04-02T21:26:20.491466Z","steps":["trace[312851075] 'agreement among raft nodes before linearized reading' (duration: 199.375854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:20.491558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:20.074963Z","time spent":"416.538186ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:26:20.491528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:20.074378Z","time spent":"416.989247ms","remote":"127.0.0.1:49792","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-02T21:26:20.491231Z","caller":"traceutil/trace.go:171","msg":"trace[1132865385] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1182; }","duration":"199.136219ms","start":"2026-04-02T21:26:20.292072Z","end":"2026-04-02T21:26:20.491209Z","steps":["trace[1132865385] 'read index received' (duration: 180.571823ms)","trace[1132865385] 'applied index is now lower than readState.Index' (duration: 18.563046ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:26:33.364762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.8163ms","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-02T21:26:33.364815Z","caller":"traceutil/trace.go:171","msg":"trace[1934961780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1226; }","duration":"829.891682ms","start":"2026-04-02T21:26:32.53491Z","end":"2026-04-02T21:26:33.364801Z","steps":["trace[1934961780] 'range keys from in-memory index tree' (duration: 829.701067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:33.36479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.537277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-02T21:26:33.364847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:32.534894Z","time spent":"829.946383ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T21:26:33.364827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.749932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T21:26:33.36484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.77931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T21:26:33.36487Z","caller":"traceutil/trace.go:171","msg":"trace[1961098036] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:1226; }","duration":"465.65727ms","start":"2026-04-02T21:26:32.899191Z","end":"2026-04-02T21:26:33.364848Z","steps":["trace[1961098036] 'range keys from in-memory index tree' (duration: 465.360924ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:33.364922Z","caller":"traceutil/trace.go:171","msg":"trace[15991248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1226; }","duration":"434.883466ms","start":"2026-04-02T21:26:32.93001Z","end":"2026-04-02T21:26:33.364894Z","steps":["trace[15991248] 'range keys from in-memory index tree' (duration: 434.664081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:33.364969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:32.899136Z","time spent":"465.821444ms","remote":"127.0.0.1:49606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.86\" "} {"level":"info","ts":"2026-04-02T21:26:33.36497Z","caller":"traceutil/trace.go:171","msg":"trace[108864330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1226; }","duration":"818.954334ms","start":"2026-04-02T21:26:32.545984Z","end":"2026-04-02T21:26:33.364938Z","steps":["trace[108864330] 'range keys from in-memory index tree' (duration: 818.540054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:33.364981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:32.929991Z","time spent":"434.975968ms","remote":"127.0.0.1:49544","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-02T21:26:33.365029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:32.545975Z","time spent":"819.041326ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T21:26:33.364652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.54053ms","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-02T21:26:33.365132Z","caller":"traceutil/trace.go:171","msg":"trace[1800694545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1226; }","duration":"816.057373ms","start":"2026-04-02T21:26:32.549066Z","end":"2026-04-02T21:26:33.365123Z","steps":["trace[1800694545] 'range keys from in-memory index tree' (duration: 815.385506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:33.365149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:32.549048Z","time spent":"816.095764ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T21:26:33.365575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.993278ms","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-02T21:26:33.365632Z","caller":"traceutil/trace.go:171","msg":"trace[1604589581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1226; }","duration":"822.09569ms","start":"2026-04-02T21:26:32.543519Z","end":"2026-04-02T21:26:33.365615Z","steps":["trace[1604589581] 'range keys from in-memory index tree' (duration: 820.812179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:33.365675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:32.543501Z","time spent":"822.159862ms","remote":"127.0.0.1:49792","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-02T21:26:49.706103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.153814ms","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-02T21:26:49.706162Z","caller":"traceutil/trace.go:171","msg":"trace[1388907097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1299; }","duration":"161.256046ms","start":"2026-04-02T21:26:49.544893Z","end":"2026-04-02T21:26:49.706149Z","steps":["trace[1388907097] 'range keys from in-memory index tree' (duration: 161.038981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:49.70617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.453701ms","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-02T21:26:49.70617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.699102ms","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-02T21:26:49.706244Z","caller":"traceutil/trace.go:171","msg":"trace[1227176423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1299; }","duration":"139.566454ms","start":"2026-04-02T21:26:49.566659Z","end":"2026-04-02T21:26:49.706225Z","steps":["trace[1227176423] 'range keys from in-memory index tree' (duration: 139.188924ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:49.70628Z","caller":"traceutil/trace.go:171","msg":"trace[1394004734] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1299; }","duration":"150.854536ms","start":"2026-04-02T21:26:49.555407Z","end":"2026-04-02T21:26:49.706261Z","steps":["trace[1394004734] 'range keys from in-memory index tree' (duration: 150.494097ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:50.118631Z","caller":"traceutil/trace.go:171","msg":"trace[1557678663] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"407.584613ms","start":"2026-04-02T21:26:49.710968Z","end":"2026-04-02T21:26:50.118552Z","steps":["trace[1557678663] 'process raft request' (duration: 407.43982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:50.118768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:49.710944Z","time spent":"407.755457ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:26:50.765028Z","caller":"traceutil/trace.go:171","msg":"trace[78882141] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"1.049145375s","start":"2026-04-02T21:26:49.715856Z","end":"2026-04-02T21:26:50.765001Z","steps":["trace[78882141] 'process raft request' (duration: 1.048569371s)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:50.765153Z","caller":"traceutil/trace.go:171","msg":"trace[1157151443] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1354; }","duration":"1.00996443s","start":"2026-04-02T21:26:49.755166Z","end":"2026-04-02T21:26:50.76513Z","steps":["trace[1157151443] 'read index received' (duration: 363.431698ms)","trace[1157151443] 'applied index is now lower than readState.Index' (duration: 646.531232ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:26:50.765171Z","caller":"traceutil/trace.go:171","msg":"trace[590214896] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"1.049250088s","start":"2026-04-02T21:26:49.715897Z","end":"2026-04-02T21:26:50.765147Z","steps":["trace[590214896] 'process raft request' (duration: 1.049048693s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:50.765335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010167266s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-04-02T21:26:50.765366Z","caller":"traceutil/trace.go:171","msg":"trace[274878945] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1302; }","duration":"1.010241788s","start":"2026-04-02T21:26:49.755115Z","end":"2026-04-02T21:26:50.765357Z","steps":["trace[274878945] 'agreement among raft nodes before linearized reading' (duration: 1.010113235s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:50.765393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:49.755096Z","time spent":"1.010288278s","remote":"127.0.0.1:49726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5354,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-02T21:26:50.76558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:49.715871Z","time spent":"1.04936025s","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:26:50.766136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:49.715838Z","time spent":"1.050250281s","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:26:50.829053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.97332ms","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-02T21:26:50.829125Z","caller":"traceutil/trace.go:171","msg":"trace[1712674759] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1303; }","duration":"693.095733ms","start":"2026-04-02T21:26:50.136014Z","end":"2026-04-02T21:26:50.82911Z","steps":["trace[1712674759] 'agreement among raft nodes before linearized reading' (duration: 692.903579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:50.829159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:50.135996Z","time spent":"693.155264ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T21:26:50.82928Z","caller":"traceutil/trace.go:171","msg":"trace[2063853357] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"603.117245ms","start":"2026-04-02T21:26:50.22614Z","end":"2026-04-02T21:26:50.829258Z","steps":["trace[2063853357] 'process raft request' (duration: 602.584432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:50.829412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:50.226118Z","time spent":"603.227587ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:26:50.929485Z","caller":"traceutil/trace.go:171","msg":"trace[687022065] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"153.673113ms","start":"2026-04-02T21:26:50.77579Z","end":"2026-04-02T21:26:50.929463Z","steps":["trace[687022065] 'process raft request' (duration: 153.523219ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:50.96809Z","caller":"traceutil/trace.go:171","msg":"trace[1372077172] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"133.711012ms","start":"2026-04-02T21:26:50.834354Z","end":"2026-04-02T21:26:50.968065Z","steps":["trace[1372077172] 'process raft request' (duration: 133.531758ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:51.150423Z","caller":"traceutil/trace.go:171","msg":"trace[1320012788] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"177.081348ms","start":"2026-04-02T21:26:50.973321Z","end":"2026-04-02T21:26:51.150402Z","steps":["trace[1320012788] 'process raft request' (duration: 176.963555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:53.150946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.65886692s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T21:26:53.151048Z","caller":"traceutil/trace.go:171","msg":"trace[1924905712] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1307; }","duration":"1.659022644s","start":"2026-04-02T21:26:51.492003Z","end":"2026-04-02T21:26:53.151026Z","steps":["trace[1924905712] 'range keys from in-memory index tree' (duration: 1.658674055s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:53.151154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.224406999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:26:53.151187Z","caller":"traceutil/trace.go:171","msg":"trace[356699457] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1307; }","duration":"1.22446628s","start":"2026-04-02T21:26:51.926709Z","end":"2026-04-02T21:26:53.151175Z","steps":["trace[356699457] 'range keys from in-memory index tree' (duration: 1.224327606s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:53.151211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:51.926688Z","time spent":"1.224517582s","remote":"127.0.0.1:49544","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-02T21:26:53.151254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028938238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-02T21:26:53.151069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.060286ms","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-02T21:26:53.151315Z","caller":"traceutil/trace.go:171","msg":"trace[684163560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1307; }","duration":"379.370873ms","start":"2026-04-02T21:26:52.771939Z","end":"2026-04-02T21:26:53.15131Z","steps":["trace[684163560] 'range keys from in-memory index tree' (duration: 378.940633ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:53.151315Z","caller":"traceutil/trace.go:171","msg":"trace[914474489] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1307; }","duration":"1.02902393s","start":"2026-04-02T21:26:52.122276Z","end":"2026-04-02T21:26:53.1513Z","steps":["trace[914474489] 'range keys from in-memory index tree' (duration: 1.028780114s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:53.150987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.035485ms","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-02T21:26:53.15137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:52.12226Z","time spent":"1.029097162s","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T21:26:53.15114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:51.491981Z","time spent":"1.659143866s","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T21:26:53.151332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:52.771923Z","time spent":"379.404374ms","remote":"127.0.0.1:49792","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-02T21:26:53.151377Z","caller":"traceutil/trace.go:171","msg":"trace[99832383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1307; }","duration":"379.463036ms","start":"2026-04-02T21:26:52.771899Z","end":"2026-04-02T21:26:53.151362Z","steps":["trace[99832383] 'range keys from in-memory index tree' (duration: 378.904232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:53.151553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:52.77188Z","time spent":"379.65963ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T21:26:53.173099Z","caller":"traceutil/trace.go:171","msg":"trace[1496979172] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1361; }","duration":"272.447616ms","start":"2026-04-02T21:26:52.900625Z","end":"2026-04-02T21:26:53.173073Z","steps":["trace[1496979172] 'read index received' (duration: 272.265112ms)","trace[1496979172] 'applied index is now lower than readState.Index' (duration: 181.274µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:26:53.173173Z","caller":"traceutil/trace.go:171","msg":"trace[645373275] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"338.499628ms","start":"2026-04-02T21:26:52.834657Z","end":"2026-04-02T21:26:53.173156Z","steps":["trace[645373275] 'process raft request' (duration: 338.253202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:53.173264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:52.834638Z","time spent":"338.57046ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:26:53.173322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.673321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-02T21:26:53.173377Z","caller":"traceutil/trace.go:171","msg":"trace[775988106] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:1308; }","duration":"272.766534ms","start":"2026-04-02T21:26:52.900594Z","end":"2026-04-02T21:26:53.17336Z","steps":["trace[775988106] 'agreement among raft nodes before linearized reading' (duration: 272.59472ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:53.626407Z","caller":"traceutil/trace.go:171","msg":"trace[379227304] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1362; }","duration":"453.221023ms","start":"2026-04-02T21:26:53.173165Z","end":"2026-04-02T21:26:53.626386Z","steps":["trace[379227304] 'read index received' (duration: 361.79643ms)","trace[379227304] 'applied index is now lower than readState.Index' (duration: 91.423623ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:26:53.626887Z","caller":"traceutil/trace.go:171","msg":"trace[1057257968] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"471.028592ms","start":"2026-04-02T21:26:53.155779Z","end":"2026-04-02T21:26:53.626808Z","steps":["trace[1057257968] 'process raft request' (duration: 470.447508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:53.627075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:53.155774Z","time spent":"471.214807ms","remote":"127.0.0.1:49792","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-02T21:26:53.627418Z","caller":"traceutil/trace.go:171","msg":"trace[290125371] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"472.614631ms","start":"2026-04-02T21:26:53.154787Z","end":"2026-04-02T21:26:53.627402Z","steps":["trace[290125371] 'process raft request' (duration: 380.090301ms)","trace[290125371] 'compare' (duration: 90.704936ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:26:53.627566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:53.154775Z","time spent":"472.740443ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:26:53.627655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.877205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:26:53.627719Z","caller":"traceutil/trace.go:171","msg":"trace[264590128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1312; }","duration":"474.950837ms","start":"2026-04-02T21:26:53.15275Z","end":"2026-04-02T21:26:53.627701Z","steps":["trace[264590128] 'agreement among raft nodes before linearized reading' (duration: 474.857734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:53.627768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:53.152731Z","time spent":"475.026358ms","remote":"127.0.0.1:49560","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-02T21:26:53.628086Z","caller":"traceutil/trace.go:171","msg":"trace[871095422] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"472.299113ms","start":"2026-04-02T21:26:53.155772Z","end":"2026-04-02T21:26:53.628072Z","steps":["trace[871095422] 'process raft request' (duration: 470.52902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:53.628182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:53.155755Z","time spent":"472.373885ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:26:53.628399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.446447ms","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-02T21:26:53.628763Z","caller":"traceutil/trace.go:171","msg":"trace[573606849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1312; }","duration":"657.294151ms","start":"2026-04-02T21:26:52.971459Z","end":"2026-04-02T21:26:53.628753Z","steps":["trace[573606849] 'agreement among raft nodes before linearized reading' (duration: 655.012326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:53.628899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:52.971444Z","time spent":"657.438535ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T21:26:53.628648Z","caller":"traceutil/trace.go:171","msg":"trace[421524450] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"472.064367ms","start":"2026-04-02T21:26:53.156564Z","end":"2026-04-02T21:26:53.628628Z","steps":["trace[421524450] 'process raft request' (duration: 469.779762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:53.630099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:53.156558Z","time spent":"473.465961ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:26:54.240576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.454362ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-02T21:26:54.240851Z","caller":"traceutil/trace.go:171","msg":"trace[2040954344] linearizableReadLoop","detail":"{readStateIndex:1368; appliedIndex:1366; }","duration":"598.516505ms","start":"2026-04-02T21:26:53.642318Z","end":"2026-04-02T21:26:54.240834Z","steps":["trace[2040954344] 'read index received' (duration: 214.764156ms)","trace[2040954344] 'applied index is now lower than readState.Index' (duration: 383.751399ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:26:54.240914Z","caller":"traceutil/trace.go:171","msg":"trace[770538312] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"607.096082ms","start":"2026-04-02T21:26:53.63379Z","end":"2026-04-02T21:26:54.240886Z","steps":["trace[770538312] 'process raft request' (duration: 606.946948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:54.240996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:53.17443Z","time spent":"1.066558494s","remote":"127.0.0.1:49606","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-02T21:26:54.241054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.069544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T21:26:54.241043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.707039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T21:26:54.241088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:53.63377Z","time spent":"607.253565ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:26:54.24111Z","caller":"traceutil/trace.go:171","msg":"trace[1740109848] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1313; }","duration":"354.127075ms","start":"2026-04-02T21:26:53.886969Z","end":"2026-04-02T21:26:54.241096Z","steps":["trace[1740109848] 'agreement among raft nodes before linearized reading' (duration: 354.047103ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:54.241122Z","caller":"traceutil/trace.go:171","msg":"trace[1034815565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1313; }","duration":"598.824442ms","start":"2026-04-02T21:26:53.642281Z","end":"2026-04-02T21:26:54.241105Z","steps":["trace[1034815565] 'agreement among raft nodes before linearized reading' (duration: 598.694549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:54.241147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:53.886927Z","time spent":"354.210697ms","remote":"127.0.0.1:57296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-04-02T21:26:54.241162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:53.642267Z","time spent":"598.885033ms","remote":"127.0.0.1:49544","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-02T21:26:55.289266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.86247ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:26:55.289402Z","caller":"traceutil/trace.go:171","msg":"trace[773813739] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1313; }","duration":"418.007884ms","start":"2026-04-02T21:26:54.871374Z","end":"2026-04-02T21:26:55.289382Z","steps":["trace[773813739] 'range keys from in-memory index tree' (duration: 417.84633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:55.289723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.585502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T21:26:55.290058Z","caller":"traceutil/trace.go:171","msg":"trace[1030932235] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"1.047770011s","start":"2026-04-02T21:26:54.242271Z","end":"2026-04-02T21:26:55.290041Z","steps":["trace[1030932235] 'process raft request' (duration: 271.79778ms)","trace[1030932235] 'compare' (duration: 775.347676ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:26:55.290116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:54.242242Z","time spent":"1.047846842s","remote":"127.0.0.1:49606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:26:55.290267Z","caller":"traceutil/trace.go:171","msg":"trace[1625624792] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"111.873276ms","start":"2026-04-02T21:26:55.178379Z","end":"2026-04-02T21:26:55.290252Z","steps":["trace[1625624792] 'process raft request' (duration: 111.802644ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:55.290288Z","caller":"traceutil/trace.go:171","msg":"trace[1650524519] linearizableReadLoop","detail":"{readStateIndex:1369; appliedIndex:1368; }","duration":"1.047512715s","start":"2026-04-02T21:26:54.242726Z","end":"2026-04-02T21:26:55.290239Z","steps":["trace[1650524519] 'read index received' (duration: 271.297317ms)","trace[1650524519] 'applied index is now lower than readState.Index' (duration: 776.213628ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:26:55.29042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.575604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T21:26:55.290413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047672688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:26:55.290474Z","caller":"traceutil/trace.go:171","msg":"trace[1405196832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1315; }","duration":"1.047770491s","start":"2026-04-02T21:26:54.242691Z","end":"2026-04-02T21:26:55.290461Z","steps":["trace[1405196832] 'agreement among raft nodes before linearized reading' (duration: 1.047658148s)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:55.29047Z","caller":"traceutil/trace.go:171","msg":"trace[536711536] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1315; }","duration":"295.651765ms","start":"2026-04-02T21:26:54.994803Z","end":"2026-04-02T21:26:55.290455Z","steps":["trace[536711536] 'agreement among raft nodes before linearized reading' (duration: 295.581023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:55.290514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:54.242678Z","time spent":"1.047829012s","remote":"127.0.0.1:49560","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-02T21:26:56.020146Z","caller":"traceutil/trace.go:171","msg":"trace[1379952430] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"376.998936ms","start":"2026-04-02T21:26:55.643121Z","end":"2026-04-02T21:26:56.02012Z","steps":["trace[1379952430] 'process raft request' (duration: 376.829952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:56.02035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:55.643075Z","time spent":"377.199471ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:26:56.026612Z","caller":"traceutil/trace.go:171","msg":"trace[538729230] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"381.163186ms","start":"2026-04-02T21:26:55.645435Z","end":"2026-04-02T21:26:56.026598Z","steps":["trace[538729230] 'process raft request' (duration: 381.042933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:56.02671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:55.645406Z","time spent":"381.260219ms","remote":"127.0.0.1:49792","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-02T21:26:56.026843Z","caller":"traceutil/trace.go:171","msg":"trace[646124243] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"380.833718ms","start":"2026-04-02T21:26:55.646003Z","end":"2026-04-02T21:26:56.026836Z","steps":["trace[646124243] 'process raft request' (duration: 380.545861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:56.026885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:55.645955Z","time spent":"380.91013ms","remote":"127.0.0.1:49792","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-02T21:26:56.027071Z","caller":"traceutil/trace.go:171","msg":"trace[731151111] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"378.020891ms","start":"2026-04-02T21:26:55.649025Z","end":"2026-04-02T21:26:56.027046Z","steps":["trace[731151111] 'process raft request' (duration: 377.773705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:56.027191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:55.649004Z","time spent":"378.121943ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:26:59.412956Z","caller":"traceutil/trace.go:171","msg":"trace[555996383] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"147.798453ms","start":"2026-04-02T21:26:59.265126Z","end":"2026-04-02T21:26:59.412924Z","steps":["trace[555996383] 'process raft request' (duration: 147.631289ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:59.420343Z","caller":"traceutil/trace.go:171","msg":"trace[2018279585] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"117.342988ms","start":"2026-04-02T21:26:59.302979Z","end":"2026-04-02T21:26:59.420322Z","steps":["trace[2018279585] 'process raft request' (duration: 116.712353ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:27:04.178409Z","caller":"traceutil/trace.go:171","msg":"trace[2125664063] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"110.34696ms","start":"2026-04-02T21:27:04.068037Z","end":"2026-04-02T21:27:04.178384Z","steps":["trace[2125664063] 'process raft request' (duration: 80.759876ms)","trace[2125664063] 'compare' (duration: 29.305037ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:27:04.178542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.071761ms","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-02T21:27:04.178625Z","caller":"traceutil/trace.go:171","msg":"trace[958167228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1388; }","duration":"112.171633ms","start":"2026-04-02T21:27:04.066432Z","end":"2026-04-02T21:27:04.178604Z","steps":["trace[958167228] 'agreement among raft nodes before linearized reading' (duration: 111.988229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:27:06.428301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909821891383681533,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:27:06.929167Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909821891383681533,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T21:27:07.068748Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.627638667s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T21:27:07.069064Z","caller":"traceutil/trace.go:171","msg":"trace[1146305122] linearizableReadLoop","detail":"{readStateIndex:1450; appliedIndex:1449; }","duration":"1.141154692s","start":"2026-04-02T21:27:05.927888Z","end":"2026-04-02T21:27:07.069043Z","steps":["trace[1146305122] 'read index received' (duration: 1.140966068s)","trace[1146305122] 'applied index is now lower than readState.Index' (duration: 187.674µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:27:07.069387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137417182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T21:27:07.069436Z","caller":"traceutil/trace.go:171","msg":"trace[1717405154] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1393; }","duration":"1.137527335s","start":"2026-04-02T21:27:05.931897Z","end":"2026-04-02T21:27:07.069424Z","steps":["trace[1717405154] 'agreement among raft nodes before linearized reading' (duration: 1.137414873s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:27:07.069516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.710775ms","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-02T21:27:07.069557Z","caller":"traceutil/trace.go:171","msg":"trace[1451994505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1393; }","duration":"875.771596ms","start":"2026-04-02T21:27:06.193775Z","end":"2026-04-02T21:27:07.069546Z","steps":["trace[1451994505] 'agreement among raft nodes before linearized reading' (duration: 875.641983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:27:07.069587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:27:06.193765Z","time spent":"875.813477ms","remote":"127.0.0.1:49792","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-02T21:27:07.069665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:27:05.931879Z","time spent":"1.13773846s","remote":"127.0.0.1:49946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-02T21:27:07.069743Z","caller":"traceutil/trace.go:171","msg":"trace[1157274679] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"1.628733074s","start":"2026-04-02T21:27:05.440999Z","end":"2026-04-02T21:27:07.069732Z","steps":["trace[1157274679] 'process raft request' (duration: 1.627913034s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:27:07.06982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:27:05.440981Z","time spent":"1.628788105s","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:27:07.069976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142090515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:27:07.070001Z","caller":"traceutil/trace.go:171","msg":"trace[752647292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1393; }","duration":"1.142116196s","start":"2026-04-02T21:27:05.927877Z","end":"2026-04-02T21:27:07.069993Z","steps":["trace[752647292] 'agreement among raft nodes before linearized reading' (duration: 1.142073265s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:27:07.070022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:27:05.927834Z","time spent":"1.142183718s","remote":"127.0.0.1:49544","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-02T21:27:07.070078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.430898ms","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-02T21:27:07.070125Z","caller":"traceutil/trace.go:171","msg":"trace[322516215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1393; }","duration":"887.51229ms","start":"2026-04-02T21:27:06.182588Z","end":"2026-04-02T21:27:07.070101Z","steps":["trace[322516215] 'agreement among raft nodes before linearized reading' (duration: 887.305765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:27:07.070338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.676528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-02T21:27:07.070341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.513444ms","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-02T21:27:07.070376Z","caller":"traceutil/trace.go:171","msg":"trace[1782556613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1393; }","duration":"888.749079ms","start":"2026-04-02T21:27:06.181619Z","end":"2026-04-02T21:27:07.070368Z","steps":["trace[1782556613] 'agreement among raft nodes before linearized reading' (duration: 888.654217ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:27:07.070398Z","caller":"traceutil/trace.go:171","msg":"trace[1655004030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1393; }","duration":"771.620736ms","start":"2026-04-02T21:27:06.298761Z","end":"2026-04-02T21:27:07.070382Z","steps":["trace[1655004030] 'agreement among raft nodes before linearized reading' (duration: 771.454822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:27:07.070407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:27:06.1816Z","time spent":"888.799191ms","remote":"127.0.0.1:49792","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-02T21:27:07.070432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:27:06.298742Z","time spent":"771.683708ms","remote":"127.0.0.1:49792","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-02T21:27:07.070485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.569007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:471"} {"level":"warn","ts":"2026-04-02T21:27:07.070344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:27:06.182568Z","time spent":"887.764566ms","remote":"127.0.0.1:49792","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":"info","ts":"2026-04-02T21:27:07.07053Z","caller":"traceutil/trace.go:171","msg":"trace[2007835582] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1393; }","duration":"920.651918ms","start":"2026-04-02T21:27:06.149866Z","end":"2026-04-02T21:27:07.070518Z","steps":["trace[2007835582] 'agreement among raft nodes before linearized reading' (duration: 920.204547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:27:07.070546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.302434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-02T21:27:07.070564Z","caller":"traceutil/trace.go:171","msg":"trace[102627835] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1393; }","duration":"865.338426ms","start":"2026-04-02T21:27:06.20522Z","end":"2026-04-02T21:27:07.070558Z","steps":["trace[102627835] 'agreement among raft nodes before linearized reading' (duration: 865.288674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:27:07.07058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:27:06.205207Z","time spent":"865.368566ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-02T21:27:07.070709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.691756ms","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-02T21:27:07.070813Z","caller":"traceutil/trace.go:171","msg":"trace[603601237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1393; }","duration":"985.827849ms","start":"2026-04-02T21:27:06.084914Z","end":"2026-04-02T21:27:07.070742Z","steps":["trace[603601237] 'agreement among raft nodes before linearized reading' (duration: 985.45194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:27:07.070849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:27:06.0849Z","time spent":"985.938881ms","remote":"127.0.0.1:49792","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-02T21:27:07.070984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:27:06.149848Z","time spent":"921.12045ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-02T21:27:07.26315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.981807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T21:27:07.263247Z","caller":"traceutil/trace.go:171","msg":"trace[1276790992] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"188.623336ms","start":"2026-04-02T21:27:07.074606Z","end":"2026-04-02T21:27:07.26323Z","steps":["trace[1276790992] 'process raft request' (duration: 47.493005ms)","trace[1276790992] 'compare' (duration: 140.868795ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:27:07.264298Z","caller":"traceutil/trace.go:171","msg":"trace[1366780082] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"188.540313ms","start":"2026-04-02T21:27:07.075743Z","end":"2026-04-02T21:27:07.264283Z","steps":["trace[1366780082] 'process raft request' (duration: 188.453421ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:27:07.264324Z","caller":"traceutil/trace.go:171","msg":"trace[5721073] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"188.623386ms","start":"2026-04-02T21:27:07.075685Z","end":"2026-04-02T21:27:07.264308Z","steps":["trace[5721073] 'process raft request' (duration: 188.420681ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:27:07.264688Z","caller":"traceutil/trace.go:171","msg":"trace[2146776358] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"188.663427ms","start":"2026-04-02T21:27:07.076006Z","end":"2026-04-02T21:27:07.264669Z","steps":["trace[2146776358] 'process raft request' (duration: 188.479032ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:27:07.264678Z","caller":"traceutil/trace.go:171","msg":"trace[72637873] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"188.674047ms","start":"2026-04-02T21:27:07.075968Z","end":"2026-04-02T21:27:07.264642Z","steps":["trace[72637873] 'process raft request' (duration: 188.275497ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:27:07.26478Z","caller":"traceutil/trace.go:171","msg":"trace[611598904] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"188.109483ms","start":"2026-04-02T21:27:07.07666Z","end":"2026-04-02T21:27:07.26477Z","steps":["trace[611598904] 'process raft request' (duration: 187.956379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:27:07.264843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.580643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:27:07.264689Z","caller":"traceutil/trace.go:171","msg":"trace[1812187232] linearizableReadLoop","detail":"{readStateIndex:1457; appliedIndex:1450; }","duration":"185.4463ms","start":"2026-04-02T21:27:07.079225Z","end":"2026-04-02T21:27:07.264671Z","steps":["trace[1812187232] 'read index received' (duration: 42.883364ms)","trace[1812187232] 'applied index is now lower than readState.Index' (duration: 142.559286ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:27:07.264884Z","caller":"traceutil/trace.go:171","msg":"trace[900715655] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"188.119373ms","start":"2026-04-02T21:27:07.076701Z","end":"2026-04-02T21:27:07.26482Z","steps":["trace[900715655] 'process raft request' (duration: 187.939979ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:27:07.264882Z","caller":"traceutil/trace.go:171","msg":"trace[1861178756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1400; }","duration":"185.668465ms","start":"2026-04-02T21:27:07.079205Z","end":"2026-04-02T21:27:07.264874Z","steps":["trace[1861178756] 'agreement among raft nodes before linearized reading' (duration: 185.526601ms)"],"step_count":1}