{"level":"warn","ts":"2026-04-07T00:05:56.933891Z","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-07T00:05:56.933976Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.69:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.69:2380","--initial-cluster=instance=https://199.204.45.69:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.69:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.69: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-07T00:05:56.934044Z","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-07T00:05:56.934053Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-04-07T00:05:56.93407Z","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-07T00:05:56.934822Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"]} {"level":"info","ts":"2026-04-07T00:05:56.934923Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.69: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-07T00:05:56.938397Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.189631ms"} {"level":"info","ts":"2026-04-07T00:05:56.945736Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fe334179879fd0aa","cluster-id":"d79a8cccc23ced73"} {"level":"info","ts":"2026-04-07T00:05:56.945851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:05:56.945879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 0"} {"level":"info","ts":"2026-04-07T00:05:56.945927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fe334179879fd0aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:05:56.945939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 1"} {"level":"info","ts":"2026-04-07T00:05:56.946004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"warn","ts":"2026-04-07T00:05:56.951188Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:05:56.952949Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:05:56.954705Z","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-07T00:05:56.956846Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fe334179879fd0aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:05:56.957131Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fe334179879fd0aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:05:56.957875Z","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-07T00:05:56.958107Z","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-07T00:05:56.958181Z","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-07T00:05:56.959691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"info","ts":"2026-04-07T00:05:56.960178Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","added-peer-id":"fe334179879fd0aa","added-peer-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-04-07T00:05:56.961124Z","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-07T00:05:56.961572Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-04-07T00:05:56.961607Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-04-07T00:05:56.962331Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fe334179879fd0aa","initial-advertise-peer-urls":["https://199.204.45.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:05:56.962365Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:05:57.646445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:05:57.6465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:05:57.646538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgPreVoteResp from fe334179879fd0aa at term 1"} {"level":"info","ts":"2026-04-07T00:05:57.646562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:05:57.646578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgVoteResp from fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-04-07T00:05:57.646599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became leader at term 2"} {"level":"info","ts":"2026-04-07T00:05:57.646616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fe334179879fd0aa elected leader fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-04-07T00:05:57.648557Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fe334179879fd0aa","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.69:2379]}","request-path":"/0/members/fe334179879fd0aa/attributes","cluster-id":"d79a8cccc23ced73","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:05:57.648601Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:05:57.649009Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:05:57.64943Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:05:57.649507Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:05:57.649627Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:05:57.649751Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:05:57.649781Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:05:57.652036Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:05:57.649184Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:05:57.660831Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.69:2379"} 2026/04/07 00:06:02 WARNING: [core] [Server #5] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T00:06:02.682602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.826838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-04-07T00:06:02.682867Z","caller":"traceutil/trace.go:171","msg":"trace[1876680130] transaction","detail":"{read_only:false; number_of_response:0; response_revision:253; }","duration":"284.403345ms","start":"2026-04-07T00:06:02.398428Z","end":"2026-04-07T00:06:02.682831Z","steps":["trace[1876680130] 'process raft request' (duration: 27.655567ms)","trace[1876680130] 'compare' (duration: 255.736856ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:02.682876Z","caller":"traceutil/trace.go:171","msg":"trace[474224538] transaction","detail":"{read_only:false; number_of_response:0; response_revision:253; }","duration":"284.426246ms","start":"2026-04-07T00:06:02.398427Z","end":"2026-04-07T00:06:02.682853Z","steps":["trace[474224538] 'process raft request' (duration: 284.308722ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:02.683068Z","caller":"traceutil/trace.go:171","msg":"trace[1086937586] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"283.048253ms","start":"2026-04-07T00:06:02.400002Z","end":"2026-04-07T00:06:02.68305Z","steps":["trace[1086937586] 'process raft request' (duration: 283.005241ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:02.683179Z","caller":"traceutil/trace.go:171","msg":"trace[1164602911] transaction","detail":"{read_only:false; number_of_response:0; response_revision:254; }","duration":"284.660983ms","start":"2026-04-07T00:06:02.398506Z","end":"2026-04-07T00:06:02.683167Z","steps":["trace[1164602911] 'process raft request' (duration: 284.413526ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:02.683454Z","caller":"traceutil/trace.go:171","msg":"trace[1016941977] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"284.87478ms","start":"2026-04-07T00:06:02.398499Z","end":"2026-04-07T00:06:02.683374Z","steps":["trace[1016941977] 'process raft request' (duration: 284.336013ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:02.683458Z","caller":"traceutil/trace.go:171","msg":"trace[46729335] transaction","detail":"{read_only:false; number_of_response:0; response_revision:253; }","duration":"284.961343ms","start":"2026-04-07T00:06:02.398485Z","end":"2026-04-07T00:06:02.683446Z","steps":["trace[46729335] 'process raft request' (duration: 284.321793ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:02.683477Z","caller":"traceutil/trace.go:171","msg":"trace[1831564949] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"284.766917ms","start":"2026-04-07T00:06:02.398698Z","end":"2026-04-07T00:06:02.683465Z","steps":["trace[1831564949] 'process raft request' (duration: 284.258031ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:02.686441Z","caller":"traceutil/trace.go:171","msg":"trace[981596374] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"167.296829ms","start":"2026-04-07T00:06:02.519124Z","end":"2026-04-07T00:06:02.686421Z","steps":["trace[981596374] 'process raft request' (duration: 167.00023ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:03.216592Z","caller":"traceutil/trace.go:171","msg":"trace[1188295238] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"106.388416ms","start":"2026-04-07T00:06:03.110164Z","end":"2026-04-07T00:06:03.216552Z","steps":["trace[1188295238] 'process raft request' (duration: 54.282123ms)","trace[1188295238] 'compare' (duration: 51.928318ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:03.645063Z","caller":"traceutil/trace.go:171","msg":"trace[245796284] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:284; }","duration":"131.516313ms","start":"2026-04-07T00:06:03.513522Z","end":"2026-04-07T00:06:03.645038Z","steps":["trace[245796284] 'read index received' (duration: 51.030119ms)","trace[245796284] 'applied index is now lower than readState.Index' (duration: 80.485004ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:03.645167Z","caller":"traceutil/trace.go:171","msg":"trace[859336442] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"194.927196ms","start":"2026-04-07T00:06:03.450203Z","end":"2026-04-07T00:06:03.64513Z","steps":["trace[859336442] 'process raft request' (duration: 114.3911ms)","trace[859336442] 'compare' (duration: 80.209526ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:06:03.645252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.662909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:06:03.645318Z","caller":"traceutil/trace.go:171","msg":"trace[880834150] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:0; response_revision:273; }","duration":"131.826573ms","start":"2026-04-07T00:06:03.513476Z","end":"2026-04-07T00:06:03.645303Z","steps":["trace[880834150] 'agreement among raft nodes before linearized reading' (duration: 131.661878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:03.645496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.858762ms","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-07T00:06:03.645552Z","caller":"traceutil/trace.go:171","msg":"trace[1595420141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:273; }","duration":"130.954596ms","start":"2026-04-07T00:06:03.514581Z","end":"2026-04-07T00:06:03.645536Z","steps":["trace[1595420141] 'agreement among raft nodes before linearized reading' (duration: 130.567874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:03.645708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.913001ms","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-07T00:06:03.645741Z","caller":"traceutil/trace.go:171","msg":"trace[1394302960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:273; }","duration":"129.479679ms","start":"2026-04-07T00:06:03.516248Z","end":"2026-04-07T00:06:03.645727Z","steps":["trace[1394302960] 'agreement among raft nodes before linearized reading' (duration: 128.910491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:06.49157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.236765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:06:06.491742Z","caller":"traceutil/trace.go:171","msg":"trace[823288564] linearizableReadLoop","detail":"{readStateIndex:310; appliedIndex:309; }","duration":"150.454475ms","start":"2026-04-07T00:06:06.341268Z","end":"2026-04-07T00:06:06.491723Z","steps":["trace[823288564] 'read index received' (duration: 41.926431ms)","trace[823288564] 'applied index is now lower than readState.Index' (duration: 108.526094ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:06:06.491934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.641341ms","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-07T00:06:06.491972Z","caller":"traceutil/trace.go:171","msg":"trace[1070513848] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:298; }","duration":"150.843807ms","start":"2026-04-07T00:06:06.341118Z","end":"2026-04-07T00:06:06.491961Z","steps":["trace[1070513848] 'agreement among raft nodes before linearized reading' (duration: 150.663711ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:06.491945Z","caller":"traceutil/trace.go:171","msg":"trace[293164268] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"181.222711ms","start":"2026-04-07T00:06:06.310646Z","end":"2026-04-07T00:06:06.491869Z","steps":["trace[293164268] 'process raft request' (duration: 72.591724ms)","trace[293164268] 'compare' (duration: 108.077519ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:06:22.28031Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003364164490672,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T00:06:22.747334Z","caller":"traceutil/trace.go:171","msg":"trace[1035548403] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"967.243995ms","start":"2026-04-07T00:06:21.780072Z","end":"2026-04-07T00:06:22.747316Z","steps":["trace[1035548403] 'read index received' (duration: 967.099051ms)","trace[1035548403] 'applied index is now lower than readState.Index' (duration: 144.374µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:22.747374Z","caller":"traceutil/trace.go:171","msg":"trace[1782212321] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"974.351321ms","start":"2026-04-07T00:06:21.772996Z","end":"2026-04-07T00:06:22.747347Z","steps":["trace[1782212321] 'process raft request' (duration: 974.216747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:22.747455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.357459ms","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-07T00:06:22.747513Z","caller":"traceutil/trace.go:171","msg":"trace[319498156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:423; }","duration":"967.464042ms","start":"2026-04-07T00:06:21.780036Z","end":"2026-04-07T00:06:22.7475Z","steps":["trace[319498156] 'agreement among raft nodes before linearized reading' (duration: 967.330778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:22.747506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.753266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:06:22.747539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:21.780021Z","time spent":"967.512724ms","remote":"127.0.0.1:53792","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-07T00:06:22.747576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.222847ms","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-07T00:06:22.74759Z","caller":"traceutil/trace.go:171","msg":"trace[247370331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:423; }","duration":"405.87767ms","start":"2026-04-07T00:06:22.341696Z","end":"2026-04-07T00:06:22.747574Z","steps":["trace[247370331] 'agreement among raft nodes before linearized reading' (duration: 405.737716ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:22.747615Z","caller":"traceutil/trace.go:171","msg":"trace[522015129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:423; }","duration":"144.298419ms","start":"2026-04-07T00:06:22.603307Z","end":"2026-04-07T00:06:22.747605Z","steps":["trace[522015129] 'agreement among raft nodes before linearized reading' (duration: 144.225417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:22.747622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:21.77297Z","time spent":"974.461334ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:06:22.747626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:22.341678Z","time spent":"405.939623ms","remote":"127.0.0.1:53516","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-07T00:06:27.696584Z","caller":"traceutil/trace.go:171","msg":"trace[2080953321] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"231.446015ms","start":"2026-04-07T00:06:27.465109Z","end":"2026-04-07T00:06:27.696555Z","steps":["trace[2080953321] 'process raft request' (duration: 231.28731ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:27.763798Z","caller":"traceutil/trace.go:171","msg":"trace[1852664907] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"295.110795ms","start":"2026-04-07T00:06:27.468653Z","end":"2026-04-07T00:06:27.763764Z","steps":["trace[1852664907] 'process raft request' (duration: 294.881118ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:27.76387Z","caller":"traceutil/trace.go:171","msg":"trace[1427272329] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"294.821856ms","start":"2026-04-07T00:06:27.469019Z","end":"2026-04-07T00:06:27.763841Z","steps":["trace[1427272329] 'process raft request' (duration: 294.6449ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:27.764015Z","caller":"traceutil/trace.go:171","msg":"trace[656505991] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"284.162157ms","start":"2026-04-07T00:06:27.479818Z","end":"2026-04-07T00:06:27.76398Z","steps":["trace[656505991] 'process raft request' (duration: 283.952711ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:27.76416Z","caller":"traceutil/trace.go:171","msg":"trace[1027707555] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"281.042258ms","start":"2026-04-07T00:06:27.483098Z","end":"2026-04-07T00:06:27.764141Z","steps":["trace[1027707555] 'process raft request' (duration: 280.7713ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:27.764108Z","caller":"traceutil/trace.go:171","msg":"trace[1200971861] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"284.229619ms","start":"2026-04-07T00:06:27.47985Z","end":"2026-04-07T00:06:27.76408Z","steps":["trace[1200971861] 'process raft request' (duration: 283.970261ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:27.764293Z","caller":"traceutil/trace.go:171","msg":"trace[762251911] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"281.171463ms","start":"2026-04-07T00:06:27.483108Z","end":"2026-04-07T00:06:27.764279Z","steps":["trace[762251911] 'process raft request' (duration: 280.861643ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:27.764333Z","caller":"traceutil/trace.go:171","msg":"trace[1237501886] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"274.13874ms","start":"2026-04-07T00:06:27.490172Z","end":"2026-04-07T00:06:27.764311Z","steps":["trace[1237501886] 'process raft request' (duration: 273.938283ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:27.764398Z","caller":"traceutil/trace.go:171","msg":"trace[2070667663] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"263.085239ms","start":"2026-04-07T00:06:27.501303Z","end":"2026-04-07T00:06:27.764388Z","steps":["trace[2070667663] 'process raft request' (duration: 262.879453ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:27.76393Z","caller":"traceutil/trace.go:171","msg":"trace[1829457155] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"286.655907ms","start":"2026-04-07T00:06:27.477216Z","end":"2026-04-07T00:06:27.763872Z","steps":["trace[1829457155] 'process raft request' (duration: 286.507702ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:27.764776Z","caller":"traceutil/trace.go:171","msg":"trace[252766759] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"134.781907ms","start":"2026-04-07T00:06:27.629974Z","end":"2026-04-07T00:06:27.764756Z","steps":["trace[252766759] 'process raft request' (duration: 134.596521ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:27.764624Z","caller":"traceutil/trace.go:171","msg":"trace[651915819] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"244.731946ms","start":"2026-04-07T00:06:27.519882Z","end":"2026-04-07T00:06:27.764614Z","steps":["trace[651915819] 'process raft request' (duration: 244.371225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:28.835439Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003364164490769,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:06:29.299201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.636214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:06:29.299697Z","caller":"traceutil/trace.go:171","msg":"trace[1810475926] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.011477298s","start":"2026-04-07T00:06:28.288178Z","end":"2026-04-07T00:06:29.299655Z","steps":["trace[1810475926] 'process raft request' (duration: 679.238324ms)","trace[1810475926] 'compare' (duration: 331.368596ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:29.299834Z","caller":"traceutil/trace.go:171","msg":"trace[656694579] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"987.168796ms","start":"2026-04-07T00:06:28.312634Z","end":"2026-04-07T00:06:29.299803Z","steps":["trace[656694579] 'process raft request' (duration: 986.806915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:29.299963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:28.288171Z","time spent":"1.011646604s","remote":"127.0.0.1:53574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20842,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:06:29.30005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:28.312609Z","time spent":"987.344912ms","remote":"127.0.0.1:53574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:06:29.300502Z","caller":"traceutil/trace.go:171","msg":"trace[696239934] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:554; }","duration":"965.431997ms","start":"2026-04-07T00:06:28.33505Z","end":"2026-04-07T00:06:29.300482Z","steps":["trace[696239934] 'read index received' (duration: 632.422359ms)","trace[696239934] 'applied index is now lower than readState.Index' (duration: 333.008658ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:29.300624Z","caller":"traceutil/trace.go:171","msg":"trace[839726464] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"881.606827ms","start":"2026-04-07T00:06:28.418993Z","end":"2026-04-07T00:06:29.3006Z","steps":["trace[839726464] 'process raft request' (duration: 881.107001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:29.300692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:28.41897Z","time spent":"881.688929ms","remote":"127.0.0.1:53574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:06:29.300879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.141694ms","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-07T00:06:29.30091Z","caller":"traceutil/trace.go:171","msg":"trace[1402019605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:536; }","duration":"161.173045ms","start":"2026-04-07T00:06:29.139727Z","end":"2026-04-07T00:06:29.3009Z","steps":["trace[1402019605] 'agreement among raft nodes before linearized reading' (duration: 161.106363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:29.300889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.83256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:06:29.300978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.660886ms","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-07T00:06:29.301091Z","caller":"traceutil/trace.go:171","msg":"trace[1289413282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:536; }","duration":"522.886683ms","start":"2026-04-07T00:06:28.778181Z","end":"2026-04-07T00:06:29.301068Z","steps":["trace[1289413282] 'agreement among raft nodes before linearized reading' (duration: 522.580964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:29.301153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:28.778162Z","time spent":"522.981337ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T00:06:29.301131Z","caller":"traceutil/trace.go:171","msg":"trace[1136491289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"965.990575ms","start":"2026-04-07T00:06:28.334961Z","end":"2026-04-07T00:06:29.300952Z","steps":["trace[1136491289] 'agreement among raft nodes before linearized reading' (duration: 965.708986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:29.301337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:28.334944Z","time spent":"966.330435ms","remote":"127.0.0.1:53516","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-07T00:06:29.301352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.195062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-07T00:06:29.301558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.743586ms","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-07T00:06:29.301635Z","caller":"traceutil/trace.go:171","msg":"trace[1005340902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:536; }","duration":"526.869ms","start":"2026-04-07T00:06:28.774749Z","end":"2026-04-07T00:06:29.301618Z","steps":["trace[1005340902] 'agreement among raft nodes before linearized reading' (duration: 526.711485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:29.301691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:28.774729Z","time spent":"526.948483ms","remote":"127.0.0.1:53792","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-07T00:06:29.302405Z","caller":"traceutil/trace.go:171","msg":"trace[2006209915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:536; }","duration":"522.730849ms","start":"2026-04-07T00:06:28.778683Z","end":"2026-04-07T00:06:29.301413Z","steps":["trace[2006209915] 'agreement among raft nodes before linearized reading' (duration: 522.050438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:29.302747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:28.778651Z","time spent":"524.047281ms","remote":"127.0.0.1:53792","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-07T00:06:29.975406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.049559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-04-07T00:06:29.975505Z","caller":"traceutil/trace.go:171","msg":"trace[599636987] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:547; }","duration":"218.178623ms","start":"2026-04-07T00:06:29.757308Z","end":"2026-04-07T00:06:29.975486Z","steps":["trace[599636987] 'range keys from in-memory index tree' (duration: 217.515682ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:30.358794Z","caller":"traceutil/trace.go:171","msg":"trace[375428894] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"553.481945ms","start":"2026-04-07T00:06:29.805286Z","end":"2026-04-07T00:06:30.358768Z","steps":["trace[375428894] 'process raft request' (duration: 553.107353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:30.358955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:29.805262Z","time spent":"553.622589ms","remote":"127.0.0.1:53574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:06:30.686595Z","caller":"traceutil/trace.go:171","msg":"trace[105351530] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:568; }","duration":"702.477533ms","start":"2026-04-07T00:06:29.984095Z","end":"2026-04-07T00:06:30.686573Z","steps":["trace[105351530] 'read index received' (duration: 374.211075ms)","trace[105351530] 'applied index is now lower than readState.Index' (duration: 328.265468ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:30.686716Z","caller":"traceutil/trace.go:171","msg":"trace[1863809861] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"827.568803ms","start":"2026-04-07T00:06:29.859139Z","end":"2026-04-07T00:06:30.686708Z","steps":["trace[1863809861] 'process raft request' (duration: 823.521885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:30.686811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:29.859112Z","time spent":"827.622714ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:06:30.686886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.779052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:23081"} {"level":"info","ts":"2026-04-07T00:06:30.686959Z","caller":"traceutil/trace.go:171","msg":"trace[443839425] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:549; }","duration":"702.881765ms","start":"2026-04-07T00:06:29.984057Z","end":"2026-04-07T00:06:30.686939Z","steps":["trace[443839425] 'agreement among raft nodes before linearized reading' (duration: 702.673069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:30.687015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:29.984042Z","time spent":"702.958428ms","remote":"127.0.0.1:53574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":1,"response size":23105,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" "} {"level":"info","ts":"2026-04-07T00:06:30.702818Z","caller":"traceutil/trace.go:171","msg":"trace[1586558165] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"302.103878ms","start":"2026-04-07T00:06:30.40069Z","end":"2026-04-07T00:06:30.702794Z","steps":["trace[1586558165] 'process raft request' (duration: 301.908971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:30.702923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.221613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.69\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-07T00:06:30.702964Z","caller":"traceutil/trace.go:171","msg":"trace[1978712779] range","detail":"{range_begin:/registry/masterleases/199.204.45.69; range_end:; response_count:1; response_revision:550; }","duration":"228.319046ms","start":"2026-04-07T00:06:30.474633Z","end":"2026-04-07T00:06:30.702952Z","steps":["trace[1978712779] 'agreement among raft nodes before linearized reading' (duration: 228.176321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:30.702947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.777489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:06:30.702979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:30.400666Z","time spent":"302.246762ms","remote":"127.0.0.1:53574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:06:30.703021Z","caller":"traceutil/trace.go:171","msg":"trace[84055146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"366.900443ms","start":"2026-04-07T00:06:30.336101Z","end":"2026-04-07T00:06:30.703001Z","steps":["trace[84055146] 'agreement among raft nodes before linearized reading' (duration: 366.763949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:30.703064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:30.336082Z","time spent":"366.974066ms","remote":"127.0.0.1:53504","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-07T00:06:33.157156Z","caller":"traceutil/trace.go:171","msg":"trace[560330135] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"362.235265ms","start":"2026-04-07T00:06:32.794879Z","end":"2026-04-07T00:06:33.157114Z","steps":["trace[560330135] 'process raft request' (duration: 358.780345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:33.157393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:32.794854Z","time spent":"362.409081ms","remote":"127.0.0.1:53616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:06:33.358139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.837148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:06:33.358458Z","caller":"traceutil/trace.go:171","msg":"trace[260980659] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"137.966238ms","start":"2026-04-07T00:06:33.220455Z","end":"2026-04-07T00:06:33.358421Z","steps":["trace[260980659] 'process raft request' (duration: 137.842664ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:33.35869Z","caller":"traceutil/trace.go:171","msg":"trace[832418468] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"301.635652ms","start":"2026-04-07T00:06:33.056818Z","end":"2026-04-07T00:06:33.358453Z","steps":["trace[832418468] 'process raft request' (duration: 149.36083ms)","trace[832418468] 'compare' (duration: 151.56508ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:06:33.359051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:33.056764Z","time spent":"302.074756ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:06:37.682881Z","caller":"traceutil/trace.go:171","msg":"trace[1572729978] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"298.712589ms","start":"2026-04-07T00:06:37.384125Z","end":"2026-04-07T00:06:37.682838Z","steps":["trace[1572729978] 'process raft request' (duration: 298.563405ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:37.851529Z","caller":"traceutil/trace.go:171","msg":"trace[1393276097] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"467.215117ms","start":"2026-04-07T00:06:37.38429Z","end":"2026-04-07T00:06:37.851505Z","steps":["trace[1393276097] 'process raft request' (duration: 467.032581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:37.851674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:37.384278Z","time spent":"467.320041ms","remote":"127.0.0.1:53792","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-07T00:06:37.851956Z","caller":"traceutil/trace.go:171","msg":"trace[455701935] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"466.763523ms","start":"2026-04-07T00:06:37.385169Z","end":"2026-04-07T00:06:37.851932Z","steps":["trace[455701935] 'process raft request' (duration: 466.301818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:37.852117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:37.38516Z","time spent":"466.877197ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:06:37.852188Z","caller":"traceutil/trace.go:171","msg":"trace[1662765073] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:614; }","duration":"435.261783ms","start":"2026-04-07T00:06:37.416904Z","end":"2026-04-07T00:06:37.852166Z","steps":["trace[1662765073] 'read index received' (duration: 265.766564ms)","trace[1662765073] 'applied index is now lower than readState.Index' (duration: 169.494419ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:37.85235Z","caller":"traceutil/trace.go:171","msg":"trace[480246949] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"466.592207ms","start":"2026-04-07T00:06:37.385747Z","end":"2026-04-07T00:06:37.852339Z","steps":["trace[480246949] 'process raft request' (duration: 466.087371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:37.85243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:37.385735Z","time spent":"466.652299ms","remote":"127.0.0.1:53792","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-07T00:06:37.852506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.263637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:06:37.852553Z","caller":"traceutil/trace.go:171","msg":"trace[1747199587] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:595; }","duration":"351.326129ms","start":"2026-04-07T00:06:37.501215Z","end":"2026-04-07T00:06:37.852541Z","steps":["trace[1747199587] 'agreement among raft nodes before linearized reading' (duration: 351.258037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:37.852592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:37.501205Z","time spent":"351.377251ms","remote":"127.0.0.1:44114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T00:06:37.852716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.035893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:06:37.852752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.126176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:06:37.852765Z","caller":"traceutil/trace.go:171","msg":"trace[1909356694] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:595; }","duration":"220.084795ms","start":"2026-04-07T00:06:37.632668Z","end":"2026-04-07T00:06:37.852753Z","steps":["trace[1909356694] 'agreement among raft nodes before linearized reading' (duration: 220.010692ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:37.852782Z","caller":"traceutil/trace.go:171","msg":"trace[1099367666] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:595; }","duration":"220.182947ms","start":"2026-04-07T00:06:37.632591Z","end":"2026-04-07T00:06:37.852774Z","steps":["trace[1099367666] 'agreement among raft nodes before linearized reading' (duration: 220.131486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:37.852858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.544936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:06:37.85289Z","caller":"traceutil/trace.go:171","msg":"trace[19639884] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:595; }","duration":"351.607518ms","start":"2026-04-07T00:06:37.501274Z","end":"2026-04-07T00:06:37.852882Z","steps":["trace[19639884] 'agreement among raft nodes before linearized reading' (duration: 351.565486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:37.852921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:37.501249Z","time spent":"351.66523ms","remote":"127.0.0.1:44114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:06:37.853078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.797636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:06:37.853118Z","caller":"traceutil/trace.go:171","msg":"trace[1687477845] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:595; }","duration":"219.864247ms","start":"2026-04-07T00:06:37.633245Z","end":"2026-04-07T00:06:37.853109Z","steps":["trace[1687477845] 'agreement among raft nodes before linearized reading' (duration: 219.808625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:37.853065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.144681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-45cn7\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-07T00:06:37.853263Z","caller":"traceutil/trace.go:171","msg":"trace[1785004175] range","detail":"{range_begin:/registry/pods/kube-system/cilium-45cn7; range_end:; response_count:1; response_revision:595; }","duration":"436.366458ms","start":"2026-04-07T00:06:37.416888Z","end":"2026-04-07T00:06:37.853254Z","steps":["trace[1785004175] 'agreement among raft nodes before linearized reading' (duration: 436.041548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:37.853311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:37.41688Z","time spent":"436.42145ms","remote":"127.0.0.1:53696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-45cn7\" "} {"level":"warn","ts":"2026-04-07T00:06:38.369427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003364164491008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:06:38.870465Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003364164491008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:06:39.371463Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003364164491008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:06:39.660095Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.795699385s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T00:06:39.660407Z","caller":"traceutil/trace.go:171","msg":"trace[829223972] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"1.79145251s","start":"2026-04-07T00:06:37.868939Z","end":"2026-04-07T00:06:39.660391Z","steps":["trace[829223972] 'read index received' (duration: 1.791283595s)","trace[829223972] 'applied index is now lower than readState.Index' (duration: 168.255µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:06:39.660532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.791530883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:06:39.660535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.791574854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:06:39.660556Z","caller":"traceutil/trace.go:171","msg":"trace[1009229966] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:596; }","duration":"1.791596345s","start":"2026-04-07T00:06:37.868951Z","end":"2026-04-07T00:06:39.660548Z","steps":["trace[1009229966] 'agreement among raft nodes before linearized reading' (duration: 1.791508692s)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:39.660583Z","caller":"traceutil/trace.go:171","msg":"trace[1272466768] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:596; }","duration":"1.791665627s","start":"2026-04-07T00:06:37.868906Z","end":"2026-04-07T00:06:39.660572Z","steps":["trace[1272466768] 'agreement among raft nodes before linearized reading' (duration: 1.791549473s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:39.660598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:37.868938Z","time spent":"1.791645236s","remote":"127.0.0.1:44158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T00:06:39.660611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:37.86889Z","time spent":"1.791714379s","remote":"127.0.0.1:44158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:06:39.660595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.608185055s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:06:39.660672Z","caller":"traceutil/trace.go:171","msg":"trace[2127881174] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:596; }","duration":"1.608292558s","start":"2026-04-07T00:06:38.05236Z","end":"2026-04-07T00:06:39.660652Z","steps":["trace[2127881174] 'agreement among raft nodes before linearized reading' (duration: 1.608128902s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:39.660673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.571161559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:06:39.660705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.657871631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:06:39.660694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.657794188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:06:39.660729Z","caller":"traceutil/trace.go:171","msg":"trace[454594413] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:596; }","duration":"1.657894412s","start":"2026-04-07T00:06:38.002827Z","end":"2026-04-07T00:06:39.660721Z","steps":["trace[454594413] 'agreement among raft nodes before linearized reading' (duration: 1.657860791s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:39.66074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326691711s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:06:39.66074Z","caller":"traceutil/trace.go:171","msg":"trace[1277400395] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:596; }","duration":"1.571248282s","start":"2026-04-07T00:06:38.089473Z","end":"2026-04-07T00:06:39.660721Z","steps":["trace[1277400395] 'agreement among raft nodes before linearized reading' (duration: 1.571119228s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:39.660765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:38.002818Z","time spent":"1.657938113s","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:06:39.660768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.571639915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:06:39.66078Z","caller":"traceutil/trace.go:171","msg":"trace[238849007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"1.326762813s","start":"2026-04-07T00:06:38.334005Z","end":"2026-04-07T00:06:39.660768Z","steps":["trace[238849007] 'agreement among raft nodes before linearized reading' (duration: 1.326716712s)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:39.660796Z","caller":"traceutil/trace.go:171","msg":"trace[2074545382] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:596; }","duration":"1.571683326s","start":"2026-04-07T00:06:38.089105Z","end":"2026-04-07T00:06:39.660788Z","steps":["trace[2074545382] 'agreement among raft nodes before linearized reading' (duration: 1.571639294s)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:39.660794Z","caller":"traceutil/trace.go:171","msg":"trace[1826913055] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:596; }","duration":"1.657914952s","start":"2026-04-07T00:06:38.002842Z","end":"2026-04-07T00:06:39.660757Z","steps":["trace[1826913055] 'agreement among raft nodes before linearized reading' (duration: 1.657704415s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:39.660803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:38.089465Z","time spent":"1.571315195s","remote":"127.0.0.1:44198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-04-07T00:06:39.660816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:38.333983Z","time spent":"1.326825005s","remote":"127.0.0.1:53516","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-07T00:06:39.660826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:38.089095Z","time spent":"1.571722527s","remote":"127.0.0.1:44210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-04-07T00:06:39.660859Z","caller":"traceutil/trace.go:171","msg":"trace[2083455073] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.79680894s","start":"2026-04-07T00:06:37.864042Z","end":"2026-04-07T00:06:39.660851Z","steps":["trace[2083455073] 'process raft request' (duration: 1.796234672s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:39.660718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:38.052352Z","time spent":"1.60835445s","remote":"127.0.0.1:44184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:06:39.660916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:37.864019Z","time spent":"1.796860182s","remote":"127.0.0.1:53696","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-07T00:06:39.660938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.699382218s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:06:39.660973Z","caller":"traceutil/trace.go:171","msg":"trace[1718224249] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:596; }","duration":"1.699414799s","start":"2026-04-07T00:06:37.961545Z","end":"2026-04-07T00:06:39.66096Z","steps":["trace[1718224249] 'agreement among raft nodes before linearized reading' (duration: 1.699356517s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:39.661001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:37.961489Z","time spent":"1.699504242s","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-07T00:06:39.660849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:38.002838Z","time spent":"1.657995655s","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T00:06:39.661065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.535739075s","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":"warn","ts":"2026-04-07T00:06:39.661101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.60898229s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:06:39.661102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.572230303s","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:5"} {"level":"info","ts":"2026-04-07T00:06:39.661133Z","caller":"traceutil/trace.go:171","msg":"trace[160356843] 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:596; }","duration":"1.535843418s","start":"2026-04-07T00:06:38.125275Z","end":"2026-04-07T00:06:39.661119Z","steps":["trace[160356843] 'agreement among raft nodes before linearized reading' (duration: 1.535747255s)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:39.661147Z","caller":"traceutil/trace.go:171","msg":"trace[1235743126] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:596; }","duration":"1.609048022s","start":"2026-04-07T00:06:38.052085Z","end":"2026-04-07T00:06:39.661133Z","steps":["trace[1235743126] 'agreement among raft nodes before linearized reading' (duration: 1.608974369s)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:39.661162Z","caller":"traceutil/trace.go:171","msg":"trace[1520984742] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:596; }","duration":"1.572296275s","start":"2026-04-07T00:06:38.08885Z","end":"2026-04-07T00:06:39.661146Z","steps":["trace[1520984742] 'agreement among raft nodes before linearized reading' (duration: 1.572210292s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:39.661171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.701134115s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:06:39.661185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.633655102s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:06:39.661186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:38.125255Z","time spent":"1.535916311s","remote":"127.0.0.1:44220","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":"warn","ts":"2026-04-07T00:06:39.661206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:38.088819Z","time spent":"1.572374698s","remote":"127.0.0.1:44210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:06:39.661217Z","caller":"traceutil/trace.go:171","msg":"trace[592668896] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:596; }","duration":"1.633700064s","start":"2026-04-07T00:06:38.027505Z","end":"2026-04-07T00:06:39.661205Z","steps":["trace[592668896] 'agreement among raft nodes before linearized reading' (duration: 1.633657413s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:39.66124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.657875491s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:06:39.661251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.70131503s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:06:39.661278Z","caller":"traceutil/trace.go:171","msg":"trace[994847699] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:596; }","duration":"1.701383192s","start":"2026-04-07T00:06:37.959888Z","end":"2026-04-07T00:06:39.661271Z","steps":["trace[994847699] 'agreement among raft nodes before linearized reading' (duration: 1.701340171s)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:39.661281Z","caller":"traceutil/trace.go:171","msg":"trace[282204088] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:596; }","duration":"1.657926893s","start":"2026-04-07T00:06:38.003344Z","end":"2026-04-07T00:06:39.66127Z","steps":["trace[282204088] 'agreement among raft nodes before linearized reading' (duration: 1.657867901s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:39.661287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.634165998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:06:39.661306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:37.959874Z","time spent":"1.701424183s","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"info","ts":"2026-04-07T00:06:39.661315Z","caller":"traceutil/trace.go:171","msg":"trace[395038397] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:596; }","duration":"1.63420246s","start":"2026-04-07T00:06:38.027105Z","end":"2026-04-07T00:06:39.661308Z","steps":["trace[395038397] 'agreement among raft nodes before linearized reading' (duration: 1.634160019s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:39.661319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:38.003339Z","time spent":"1.657968254s","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-07T00:06:39.661341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:38.027101Z","time spent":"1.634233761s","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:06:39.661371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.792158193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:06:39.661378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.586412283s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:06:39.661247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:38.027501Z","time spent":"1.633739045s","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"info","ts":"2026-04-07T00:06:39.661414Z","caller":"traceutil/trace.go:171","msg":"trace[1404631822] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:596; }","duration":"1.586487975s","start":"2026-04-07T00:06:38.074917Z","end":"2026-04-07T00:06:39.661405Z","steps":["trace[1404631822] 'agreement among raft nodes before linearized reading' (duration: 1.586428644s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:39.661446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:38.074894Z","time spent":"1.586543387s","remote":"127.0.0.1:44198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T00:06:39.661129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.634352614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:06:39.661475Z","caller":"traceutil/trace.go:171","msg":"trace[1830744036] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:596; }","duration":"1.634720966s","start":"2026-04-07T00:06:38.026749Z","end":"2026-04-07T00:06:39.66147Z","steps":["trace[1830744036] 'agreement among raft nodes before linearized reading' (duration: 1.634344645s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:39.661493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:38.026736Z","time spent":"1.634751917s","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T00:06:39.660679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.534904179s","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\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:06:39.661504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536369635s","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\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:06:39.661535Z","caller":"traceutil/trace.go:171","msg":"trace[708992159] 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:596; }","duration":"1.535786756s","start":"2026-04-07T00:06:38.125743Z","end":"2026-04-07T00:06:39.66153Z","steps":["trace[708992159] 'agreement among raft nodes before linearized reading' (duration: 1.534874277s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:39.661551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:38.125735Z","time spent":"1.535810858s","remote":"127.0.0.1:44220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"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\" limit:500 "} {"level":"info","ts":"2026-04-07T00:06:39.661565Z","caller":"traceutil/trace.go:171","msg":"trace[1120955246] 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:596; }","duration":"1.536440158s","start":"2026-04-07T00:06:38.125102Z","end":"2026-04-07T00:06:39.661542Z","steps":["trace[1120955246] 'agreement among raft nodes before linearized reading' (duration: 1.536350455s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:39.661188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:38.052077Z","time spent":"1.609098453s","remote":"127.0.0.1:44184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T00:06:39.66158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.586671552s","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:5"} {"level":"info","ts":"2026-04-07T00:06:39.66121Z","caller":"traceutil/trace.go:171","msg":"trace[391764626] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:596; }","duration":"1.701179026s","start":"2026-04-07T00:06:37.960015Z","end":"2026-04-07T00:06:39.661194Z","steps":["trace[391764626] 'agreement among raft nodes before linearized reading' (duration: 1.701126464s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:39.661617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:38.125093Z","time spent":"1.53650644s","remote":"127.0.0.1:44220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"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\" limit:10000 "} {"level":"info","ts":"2026-04-07T00:06:39.661624Z","caller":"traceutil/trace.go:171","msg":"trace[834331973] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:596; }","duration":"1.586726373s","start":"2026-04-07T00:06:38.074888Z","end":"2026-04-07T00:06:39.661615Z","steps":["trace[834331973] 'agreement among raft nodes before linearized reading' (duration: 1.586668241s)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:39.661392Z","caller":"traceutil/trace.go:171","msg":"trace[1689203837] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:596; }","duration":"1.792204464s","start":"2026-04-07T00:06:37.869182Z","end":"2026-04-07T00:06:39.661386Z","steps":["trace[1689203837] 'agreement among raft nodes before linearized reading' (duration: 1.792166593s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:39.661701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:38.074881Z","time spent":"1.586767274s","remote":"127.0.0.1:44198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:06:39.661661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:37.960007Z","time spent":"1.70163541s","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:06:39.661737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:37.869171Z","time spent":"1.792552236s","remote":"127.0.0.1:44158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"warn","ts":"2026-04-07T00:06:39.66162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.608895927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:06:39.661787Z","caller":"traceutil/trace.go:171","msg":"trace[2118127609] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:596; }","duration":"1.609063952s","start":"2026-04-07T00:06:38.05271Z","end":"2026-04-07T00:06:39.661774Z","steps":["trace[2118127609] 'agreement among raft nodes before linearized reading' (duration: 1.608892507s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:39.661829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:38.052705Z","time spent":"1.609107503s","remote":"127.0.0.1:44184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-07T00:06:40.355998Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003364164491014,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:06:40.604081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.272104ms","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-07T00:06:40.604406Z","caller":"traceutil/trace.go:171","msg":"trace[228721478] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:596; }","duration":"712.609574ms","start":"2026-04-07T00:06:39.891771Z","end":"2026-04-07T00:06:40.604381Z","steps":["trace[228721478] 'range keys from in-memory index tree' (duration: 712.252643ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:40.605157Z","caller":"traceutil/trace.go:171","msg":"trace[92151882] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"749.514956ms","start":"2026-04-07T00:06:39.855613Z","end":"2026-04-07T00:06:40.605128Z","steps":["trace[92151882] 'read index received' (duration: 707.540674ms)","trace[92151882] 'applied index is now lower than readState.Index' (duration: 41.973082ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:40.605303Z","caller":"traceutil/trace.go:171","msg":"trace[198706791] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"912.423496ms","start":"2026-04-07T00:06:39.692796Z","end":"2026-04-07T00:06:40.60522Z","steps":["trace[198706791] 'process raft request' (duration: 870.376911ms)","trace[198706791] 'compare' (duration: 41.789027ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:06:40.605542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.80598ms","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-07T00:06:40.605574Z","caller":"traceutil/trace.go:171","msg":"trace[1609830957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"746.861042ms","start":"2026-04-07T00:06:39.858706Z","end":"2026-04-07T00:06:40.605567Z","steps":["trace[1609830957] 'agreement among raft nodes before linearized reading' (duration: 746.768589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:40.605594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:39.858697Z","time spent":"746.891813ms","remote":"127.0.0.1:53792","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-07T00:06:40.605597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.786118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.69\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-07T00:06:40.605661Z","caller":"traceutil/trace.go:171","msg":"trace[1943650308] range","detail":"{range_begin:/registry/masterleases/199.204.45.69; range_end:; response_count:1; response_revision:597; }","duration":"129.875981ms","start":"2026-04-07T00:06:40.47577Z","end":"2026-04-07T00:06:40.605646Z","steps":["trace[1943650308] 'agreement among raft nodes before linearized reading' (duration: 129.687615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:40.605817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:39.692758Z","time spent":"912.645773ms","remote":"127.0.0.1:53792","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-07T00:06:40.605888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.272199ms","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-07T00:06:40.605939Z","caller":"traceutil/trace.go:171","msg":"trace[942231297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:597; }","duration":"750.377393ms","start":"2026-04-07T00:06:39.855549Z","end":"2026-04-07T00:06:40.605926Z","steps":["trace[942231297] 'agreement among raft nodes before linearized reading' (duration: 750.202498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:40.605973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:39.855526Z","time spent":"750.441155ms","remote":"127.0.0.1:53792","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-07T00:06:40.605543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.496112ms","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-07T00:06:40.606276Z","caller":"traceutil/trace.go:171","msg":"trace[1754331430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"748.253476ms","start":"2026-04-07T00:06:39.858008Z","end":"2026-04-07T00:06:40.606262Z","steps":["trace[1754331430] 'agreement among raft nodes before linearized reading' (duration: 747.348367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:40.606494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:39.858Z","time spent":"748.469193ms","remote":"127.0.0.1:53792","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-07T00:06:41.573875Z","caller":"traceutil/trace.go:171","msg":"trace[1390356415] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"130.345646ms","start":"2026-04-07T00:06:41.443302Z","end":"2026-04-07T00:06:41.573647Z","steps":["trace[1390356415] 'process raft request' (duration: 126.022939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:42.776977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.751545ms","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-07T00:06:42.777059Z","caller":"traceutil/trace.go:171","msg":"trace[919225312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:636; }","duration":"151.870969ms","start":"2026-04-07T00:06:42.625169Z","end":"2026-04-07T00:06:42.77704Z","steps":["trace[919225312] 'range keys from in-memory index tree' (duration: 151.57372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:42.777087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.198009ms","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-07T00:06:42.777146Z","caller":"traceutil/trace.go:171","msg":"trace[595890378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:636; }","duration":"146.282062ms","start":"2026-04-07T00:06:42.630846Z","end":"2026-04-07T00:06:42.777128Z","steps":["trace[595890378] 'range keys from in-memory index tree' (duration: 146.073366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:42.777278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.427997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-07T00:06:42.777294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.991341ms","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-07T00:06:42.777344Z","caller":"traceutil/trace.go:171","msg":"trace[447787790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"146.51971ms","start":"2026-04-07T00:06:42.630807Z","end":"2026-04-07T00:06:42.777327Z","steps":["trace[447787790] 'range keys from in-memory index tree' (duration: 146.297663ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:42.777351Z","caller":"traceutil/trace.go:171","msg":"trace[1791787066] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:636; }","duration":"168.051133ms","start":"2026-04-07T00:06:42.609284Z","end":"2026-04-07T00:06:42.777336Z","steps":["trace[1791787066] 'range keys from in-memory index tree' (duration: 167.833956ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:45.51933Z","caller":"traceutil/trace.go:171","msg":"trace[1498484415] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"110.560829ms","start":"2026-04-07T00:06:45.408744Z","end":"2026-04-07T00:06:45.519304Z","steps":["trace[1498484415] 'process raft request' (duration: 110.274709ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:45.90739Z","caller":"traceutil/trace.go:171","msg":"trace[1219198300] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"167.385032ms","start":"2026-04-07T00:06:45.739958Z","end":"2026-04-07T00:06:45.907343Z","steps":["trace[1219198300] 'process raft request' (duration: 167.01998ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:45.914943Z","caller":"traceutil/trace.go:171","msg":"trace[1206407411] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"147.15841ms","start":"2026-04-07T00:06:45.767766Z","end":"2026-04-07T00:06:45.914924Z","steps":["trace[1206407411] 'process raft request' (duration: 146.591352ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:51.015595Z","caller":"traceutil/trace.go:171","msg":"trace[2048409921] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"131.284646ms","start":"2026-04-07T00:06:50.884282Z","end":"2026-04-07T00:06:51.015567Z","steps":["trace[2048409921] 'process raft request' (duration: 131.243715ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:51.01581Z","caller":"traceutil/trace.go:171","msg":"trace[1155722440] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:744; }","duration":"135.648435ms","start":"2026-04-07T00:06:50.880145Z","end":"2026-04-07T00:06:51.015793Z","steps":["trace[1155722440] 'read index received' (duration: 52.00432ms)","trace[1155722440] 'applied index is now lower than readState.Index' (duration: 83.642365ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:51.015798Z","caller":"traceutil/trace.go:171","msg":"trace[2019268003] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"158.50171ms","start":"2026-04-07T00:06:50.857277Z","end":"2026-04-07T00:06:51.015778Z","steps":["trace[2019268003] 'process raft request' (duration: 158.16823ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:51.015875Z","caller":"traceutil/trace.go:171","msg":"trace[2098585352] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"183.08918ms","start":"2026-04-07T00:06:50.832779Z","end":"2026-04-07T00:06:51.015869Z","steps":["trace[2098585352] 'process raft request' (duration: 99.363193ms)","trace[2098585352] 'compare' (duration: 83.172239ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:06:51.015955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.366612ms","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-07T00:06:51.016837Z","caller":"traceutil/trace.go:171","msg":"trace[259280985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:717; }","duration":"186.275181ms","start":"2026-04-07T00:06:50.830546Z","end":"2026-04-07T00:06:51.016822Z","steps":["trace[259280985] 'agreement among raft nodes before linearized reading' (duration: 185.2898ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:53.516079Z","caller":"traceutil/trace.go:171","msg":"trace[393465051] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"122.634522ms","start":"2026-04-07T00:06:53.393385Z","end":"2026-04-07T00:06:53.516019Z","steps":["trace[393465051] 'process raft request' (duration: 122.426156ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:54.294595Z","caller":"traceutil/trace.go:171","msg":"trace[19238795] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"443.091194ms","start":"2026-04-07T00:06:53.851485Z","end":"2026-04-07T00:06:54.294576Z","steps":["trace[19238795] 'process raft request' (duration: 442.96498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:54.294768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:53.851462Z","time spent":"443.237599ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:06:55.008238Z","caller":"traceutil/trace.go:171","msg":"trace[743332040] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"111.320943ms","start":"2026-04-07T00:06:54.896889Z","end":"2026-04-07T00:06:55.00821Z","steps":["trace[743332040] 'process raft request' (duration: 111.154228ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:58.472314Z","caller":"traceutil/trace.go:171","msg":"trace[1884653056] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"926.43766ms","start":"2026-04-07T00:06:57.54585Z","end":"2026-04-07T00:06:58.472288Z","steps":["trace[1884653056] 'process raft request' (duration: 926.184342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:58.472503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:06:57.545833Z","time spent":"926.560894ms","remote":"127.0.0.1:53696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:06:58.7614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.669757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:06:58.761654Z","caller":"traceutil/trace.go:171","msg":"trace[1083260234] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"285.409108ms","start":"2026-04-07T00:06:58.47622Z","end":"2026-04-07T00:06:58.761629Z","steps":["trace[1083260234] 'process raft request' (duration: 171.423961ms)","trace[1083260234] 'compare' (duration: 113.565793ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:06.186994Z","caller":"traceutil/trace.go:171","msg":"trace[138854848] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"128.402494ms","start":"2026-04-07T00:07:06.058564Z","end":"2026-04-07T00:07:06.186967Z","steps":["trace[138854848] 'process raft request' (duration: 128.216679ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:25.102889Z","caller":"traceutil/trace.go:171","msg":"trace[1609358023] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"103.377261ms","start":"2026-04-07T00:07:24.99949Z","end":"2026-04-07T00:07:25.102867Z","steps":["trace[1609358023] 'process raft request' (duration: 33.572185ms)","trace[1609358023] 'compare' (duration: 69.697182ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:25.77323Z","caller":"traceutil/trace.go:171","msg":"trace[868816079] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"192.998453ms","start":"2026-04-07T00:07:25.580206Z","end":"2026-04-07T00:07:25.773204Z","steps":["trace[868816079] 'process raft request' (duration: 192.827588ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:29.612243Z","caller":"traceutil/trace.go:171","msg":"trace[145579977] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"275.682078ms","start":"2026-04-07T00:07:29.336539Z","end":"2026-04-07T00:07:29.612221Z","steps":["trace[145579977] 'read index received' (duration: 275.504662ms)","trace[145579977] 'applied index is now lower than readState.Index' (duration: 176.626µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:29.612316Z","caller":"traceutil/trace.go:171","msg":"trace[197270160] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"340.199336ms","start":"2026-04-07T00:07:29.272088Z","end":"2026-04-07T00:07:29.612288Z","steps":["trace[197270160] 'process raft request' (duration: 340.01099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:29.612405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.833402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:07:29.612466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:29.272071Z","time spent":"340.301478ms","remote":"127.0.0.1:53792","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-07T00:07:29.612525Z","caller":"traceutil/trace.go:171","msg":"trace[1085838165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"275.982827ms","start":"2026-04-07T00:07:29.336502Z","end":"2026-04-07T00:07:29.612485Z","steps":["trace[1085838165] 'agreement among raft nodes before linearized reading' (duration: 275.832662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:29.612686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.38775ms","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-07T00:07:29.613045Z","caller":"traceutil/trace.go:171","msg":"trace[1684430960] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:956; }","duration":"166.798313ms","start":"2026-04-07T00:07:29.446228Z","end":"2026-04-07T00:07:29.613026Z","steps":["trace[1684430960] 'agreement among raft nodes before linearized reading' (duration: 166.239265ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:32.232265Z","caller":"traceutil/trace.go:171","msg":"trace[811134868] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"158.222851ms","start":"2026-04-07T00:07:32.074003Z","end":"2026-04-07T00:07:32.232226Z","steps":["trace[811134868] 'process raft request' (duration: 158.093477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:34.097242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.593608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:07:34.097367Z","caller":"traceutil/trace.go:171","msg":"trace[1118907549] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"274.493261ms","start":"2026-04-07T00:07:33.822861Z","end":"2026-04-07T00:07:34.097354Z","steps":["trace[1118907549] 'process raft request' (duration: 153.725799ms)","trace[1118907549] 'compare' (duration: 120.515884ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:34.097615Z","caller":"traceutil/trace.go:171","msg":"trace[1449914043] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"274.697937ms","start":"2026-04-07T00:07:33.822902Z","end":"2026-04-07T00:07:34.0976Z","steps":["trace[1449914043] 'process raft request' (duration: 274.634575ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:34.097729Z","caller":"traceutil/trace.go:171","msg":"trace[371050485] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"274.872003ms","start":"2026-04-07T00:07:33.822848Z","end":"2026-04-07T00:07:34.09772Z","steps":["trace[371050485] 'process raft request' (duration: 274.47251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:35.716284Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003364164491802,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:07:36.217317Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003364164491802,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:07:36.717932Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003364164491802,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:07:36.975845Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.840536987s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T00:07:36.976211Z","caller":"traceutil/trace.go:171","msg":"trace[1927870450] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"1.841019153s","start":"2026-04-07T00:07:35.135164Z","end":"2026-04-07T00:07:36.976183Z","steps":["trace[1927870450] 'process raft request' (duration: 1.840843408s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:36.976345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:35.13514Z","time spent":"1.841129267s","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:07:37.33613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000287727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T00:07:37.336216Z","caller":"traceutil/trace.go:171","msg":"trace[1346381015] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000425592s","start":"2026-04-07T00:07:35.33577Z","end":"2026-04-07T00:07:37.336196Z","steps":["trace[1346381015] 'agreement among raft nodes before linearized reading' (duration: 2.000320918s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:37.336265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:35.335745Z","time spent":"2.000510304s","remote":"127.0.0.1:53516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 00:07:37 WARNING: [core] [Server #5] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T00:07:38.350572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.406787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:07:38.351125Z","caller":"traceutil/trace.go:171","msg":"trace[501247431] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"2.526742414s","start":"2026-04-07T00:07:35.824357Z","end":"2026-04-07T00:07:38.351099Z","steps":["trace[501247431] 'process raft request' (duration: 1.831727238s)","trace[501247431] 'compare' (duration: 693.792978ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:07:38.351261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:35.82433Z","time spent":"2.526852268s","remote":"127.0.0.1:53792","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-07T00:07:38.460263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.519006ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-07T00:07:38.46036Z","caller":"traceutil/trace.go:171","msg":"trace[187094672] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1019; }","duration":"3.244323026s","start":"2026-04-07T00:07:35.21602Z","end":"2026-04-07T00:07:38.460343Z","steps":["trace[187094672] 'read index received' (duration: 1.760033053s)","trace[187094672] 'applied index is now lower than readState.Index' (duration: 1.484288713s)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:07:38.460524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.042345896s","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":"warn","ts":"2026-04-07T00:07:38.460571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.244524622s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-07T00:07:38.460588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.359035882s","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-07T00:07:38.460586Z","caller":"traceutil/trace.go:171","msg":"trace[1734271380] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:980; }","duration":"3.04246465s","start":"2026-04-07T00:07:35.418109Z","end":"2026-04-07T00:07:38.460573Z","steps":["trace[1734271380] 'agreement among raft nodes before linearized reading' (duration: 3.042348307s)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:38.460605Z","caller":"traceutil/trace.go:171","msg":"trace[586792900] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:980; }","duration":"3.244614085s","start":"2026-04-07T00:07:35.215981Z","end":"2026-04-07T00:07:38.460595Z","steps":["trace[586792900] 'agreement among raft nodes before linearized reading' (duration: 3.2444509s)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:38.460616Z","caller":"traceutil/trace.go:171","msg":"trace[926988094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:980; }","duration":"2.359079064s","start":"2026-04-07T00:07:36.101528Z","end":"2026-04-07T00:07:38.460607Z","steps":["trace[926988094] 'agreement among raft nodes before linearized reading' (duration: 2.3589711s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:38.460636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:36.101518Z","time spent":"2.359112824s","remote":"127.0.0.1:53792","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-07T00:07:38.460639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:35.41809Z","time spent":"3.042523152s","remote":"127.0.0.1:53910","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":"warn","ts":"2026-04-07T00:07:38.460643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:35.215963Z","time spent":"3.244670537s","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T00:07:38.460638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.359019372s","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-07T00:07:38.460704Z","caller":"traceutil/trace.go:171","msg":"trace[609245750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:980; }","duration":"2.359119155s","start":"2026-04-07T00:07:36.101568Z","end":"2026-04-07T00:07:38.460687Z","steps":["trace[609245750] 'agreement among raft nodes before linearized reading' (duration: 2.358926339s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:38.460738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.816577538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:07:38.460769Z","caller":"traceutil/trace.go:171","msg":"trace[132580079] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:980; }","duration":"1.81663626s","start":"2026-04-07T00:07:36.644124Z","end":"2026-04-07T00:07:38.460761Z","steps":["trace[132580079] 'agreement among raft nodes before linearized reading' (duration: 1.816560488s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:38.460788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:36.64411Z","time spent":"1.816672381s","remote":"127.0.0.1:53866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:07:38.460778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:36.101553Z","time spent":"2.359202147s","remote":"127.0.0.1:53792","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-07T00:07:38.460847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.622508253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:07:38.460882Z","caller":"traceutil/trace.go:171","msg":"trace[1646478275] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:980; }","duration":"2.622580855s","start":"2026-04-07T00:07:35.838295Z","end":"2026-04-07T00:07:38.460876Z","steps":["trace[1646478275] 'agreement among raft nodes before linearized reading' (duration: 2.622472772s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:38.460903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:35.838276Z","time spent":"2.622620727s","remote":"127.0.0.1:53598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":96,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:07:38.460944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.111164236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:07:38.460978Z","caller":"traceutil/trace.go:171","msg":"trace[654229588] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:980; }","duration":"2.111229018s","start":"2026-04-07T00:07:36.349736Z","end":"2026-04-07T00:07:38.460965Z","steps":["trace[654229588] 'agreement among raft nodes before linearized reading' (duration: 2.111176206s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:38.461007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:36.349718Z","time spent":"2.111281449s","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:07:38.461012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.359460075s","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-07T00:07:38.461045Z","caller":"traceutil/trace.go:171","msg":"trace[876050830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:980; }","duration":"2.359534948s","start":"2026-04-07T00:07:36.101503Z","end":"2026-04-07T00:07:38.461038Z","steps":["trace[876050830] 'agreement among raft nodes before linearized reading' (duration: 2.359459336s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:38.461063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:36.10148Z","time spent":"2.359577739s","remote":"127.0.0.1:53792","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-07T00:07:38.461078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.820690972s","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-07T00:07:38.4611Z","caller":"traceutil/trace.go:171","msg":"trace[2125299192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:980; }","duration":"2.820756005s","start":"2026-04-07T00:07:35.640337Z","end":"2026-04-07T00:07:38.461093Z","steps":["trace[2125299192] 'agreement among raft nodes before linearized reading' (duration: 2.820663292s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:38.461117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:35.640319Z","time spent":"2.820793076s","remote":"127.0.0.1:53792","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-07T00:07:38.462032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125659772s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:07:38.462152Z","caller":"traceutil/trace.go:171","msg":"trace[48381984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"1.125809257s","start":"2026-04-07T00:07:37.336327Z","end":"2026-04-07T00:07:38.462136Z","steps":["trace[48381984] 'agreement among raft nodes before linearized reading' (duration: 1.124553987s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:38.462247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:07:37.33631Z","time spent":"1.125924211s","remote":"127.0.0.1:53504","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-07T00:07:48.642388Z","caller":"traceutil/trace.go:171","msg":"trace[488004323] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"112.157039ms","start":"2026-04-07T00:07:48.530208Z","end":"2026-04-07T00:07:48.642365Z","steps":["trace[488004323] 'process raft request' (duration: 101.605995ms)","trace[488004323] 'compare' (duration: 10.386739ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:48.642705Z","caller":"traceutil/trace.go:171","msg":"trace[1165192544] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"111.154358ms","start":"2026-04-07T00:07:48.531542Z","end":"2026-04-07T00:07:48.642696Z","steps":["trace[1165192544] 'process raft request' (duration: 110.786796ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:48.642713Z","caller":"traceutil/trace.go:171","msg":"trace[1850138301] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"110.817317ms","start":"2026-04-07T00:07:48.531872Z","end":"2026-04-07T00:07:48.642689Z","steps":["trace[1850138301] 'process raft request' (duration: 110.741764ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:52.805285Z","caller":"traceutil/trace.go:171","msg":"trace[1226242336] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"124.429429ms","start":"2026-04-07T00:07:52.680825Z","end":"2026-04-07T00:07:52.805255Z","steps":["trace[1226242336] 'process raft request' (duration: 124.374107ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:52.805337Z","caller":"traceutil/trace.go:171","msg":"trace[949873419] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"124.931535ms","start":"2026-04-07T00:07:52.680368Z","end":"2026-04-07T00:07:52.8053Z","steps":["trace[949873419] 'process raft request' (duration: 124.545423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:01.078926Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003364164492007,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:08:01.260395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:00.481227Z","time spent":"779.164313ms","remote":"127.0.0.1:53562","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-07T00:08:01.424917Z","caller":"traceutil/trace.go:171","msg":"trace[365214873] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1098; }","duration":"847.049028ms","start":"2026-04-07T00:08:00.577833Z","end":"2026-04-07T00:08:01.424882Z","steps":["trace[365214873] 'read index received' (duration: 682.447375ms)","trace[365214873] 'applied index is now lower than readState.Index' (duration: 164.600663ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:01.425024Z","caller":"traceutil/trace.go:171","msg":"trace[975413229] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"909.90697ms","start":"2026-04-07T00:08:00.515051Z","end":"2026-04-07T00:08:01.424957Z","steps":["trace[975413229] 'process raft request' (duration: 909.659522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:01.425386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:00.515027Z","time spent":"910.273942ms","remote":"127.0.0.1:53792","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-07T00:08:01.425299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.444881ms","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-07T00:08:01.425734Z","caller":"traceutil/trace.go:171","msg":"trace[714061405] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1053; }","duration":"847.816493ms","start":"2026-04-07T00:08:00.577788Z","end":"2026-04-07T00:08:01.425604Z","steps":["trace[714061405] 'agreement among raft nodes before linearized reading' (duration: 847.206763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:01.425943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:00.577772Z","time spent":"848.013849ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T00:08:01.477059Z","caller":"traceutil/trace.go:171","msg":"trace[1059922338] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"215.53909ms","start":"2026-04-07T00:08:01.261487Z","end":"2026-04-07T00:08:01.477026Z","steps":["trace[1059922338] 'process raft request' (duration: 215.175518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:01.47709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.309347ms","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-07T00:08:01.477158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.614552ms","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-07T00:08:01.477169Z","caller":"traceutil/trace.go:171","msg":"trace[1482527766] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1054; }","duration":"633.42597ms","start":"2026-04-07T00:08:00.843723Z","end":"2026-04-07T00:08:01.477149Z","steps":["trace[1482527766] 'agreement among raft nodes before linearized reading' (duration: 633.132741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:01.477189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.821943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-07T00:08:01.477228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:00.843712Z","time spent":"633.506214ms","remote":"127.0.0.1:53792","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":"info","ts":"2026-04-07T00:08:01.477235Z","caller":"traceutil/trace.go:171","msg":"trace[2140264966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1054; }","duration":"766.909197ms","start":"2026-04-07T00:08:00.710316Z","end":"2026-04-07T00:08:01.477225Z","steps":["trace[2140264966] 'agreement among raft nodes before linearized reading' (duration: 766.785393ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:01.477226Z","caller":"traceutil/trace.go:171","msg":"trace[1942122832] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1054; }","duration":"691.713925ms","start":"2026-04-07T00:08:00.785491Z","end":"2026-04-07T00:08:01.477205Z","steps":["trace[1942122832] 'agreement among raft nodes before linearized reading' (duration: 691.504069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:01.477224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.490037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:08:01.477275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:00.710297Z","time spent":"766.966938ms","remote":"127.0.0.1:53792","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-07T00:08:01.47729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:00.785463Z","time spent":"691.811389ms","remote":"127.0.0.1:53792","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-07T00:08:01.477335Z","caller":"traceutil/trace.go:171","msg":"trace[1529309817] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1054; }","duration":"442.672743ms","start":"2026-04-07T00:08:01.034635Z","end":"2026-04-07T00:08:01.477308Z","steps":["trace[1529309817] 'agreement among raft nodes before linearized reading' (duration: 442.441175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:01.477392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:01.034617Z","time spent":"442.761856ms","remote":"127.0.0.1:44134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:08:01.477454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.875699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:08:01.477518Z","caller":"traceutil/trace.go:171","msg":"trace[1897004960] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1054; }","duration":"893.006374ms","start":"2026-04-07T00:08:00.584496Z","end":"2026-04-07T00:08:01.477502Z","steps":["trace[1897004960] 'agreement among raft nodes before linearized reading' (duration: 892.339402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:01.477412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.519392ms","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-07T00:08:01.477574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:00.584476Z","time spent":"893.082686ms","remote":"127.0.0.1:53682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:08:01.477625Z","caller":"traceutil/trace.go:171","msg":"trace[882425729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1054; }","duration":"641.808691ms","start":"2026-04-07T00:08:00.835798Z","end":"2026-04-07T00:08:01.477607Z","steps":["trace[882425729] 'agreement among raft nodes before linearized reading' (duration: 641.557653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:01.477679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:00.835782Z","time spent":"641.879574ms","remote":"127.0.0.1:53792","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-07T00:08:01.476979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.245115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:01.477959Z","caller":"traceutil/trace.go:171","msg":"trace[1023712220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1054; }","duration":"141.267698ms","start":"2026-04-07T00:08:01.336676Z","end":"2026-04-07T00:08:01.477943Z","steps":["trace[1023712220] 'agreement among raft nodes before linearized reading' (duration: 140.203384ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:01.644802Z","caller":"traceutil/trace.go:171","msg":"trace[764659529] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"162.755162ms","start":"2026-04-07T00:08:01.482023Z","end":"2026-04-07T00:08:01.644778Z","steps":["trace[764659529] 'process raft request' (duration: 162.662039ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:01.645037Z","caller":"traceutil/trace.go:171","msg":"trace[1838954981] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"162.602136ms","start":"2026-04-07T00:08:01.482415Z","end":"2026-04-07T00:08:01.645017Z","steps":["trace[1838954981] 'process raft request' (duration: 162.330537ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:01.645105Z","caller":"traceutil/trace.go:171","msg":"trace[564405634] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"161.47784ms","start":"2026-04-07T00:08:01.48359Z","end":"2026-04-07T00:08:01.645068Z","steps":["trace[564405634] 'process raft request' (duration: 161.344356ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:01.644832Z","caller":"traceutil/trace.go:171","msg":"trace[1173078697] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"162.862965ms","start":"2026-04-07T00:08:01.481935Z","end":"2026-04-07T00:08:01.644798Z","steps":["trace[1173078697] 'process raft request' (duration: 75.522312ms)","trace[1173078697] 'compare' (duration: 87.094405ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:01.64533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.611223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-07T00:08:01.645407Z","caller":"traceutil/trace.go:171","msg":"trace[974079530] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1059; }","duration":"165.731617ms","start":"2026-04-07T00:08:01.479662Z","end":"2026-04-07T00:08:01.645393Z","steps":["trace[974079530] 'agreement among raft nodes before linearized reading' (duration: 165.576932ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:01.645693Z","caller":"traceutil/trace.go:171","msg":"trace[1228062708] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1102; }","duration":"128.860516ms","start":"2026-04-07T00:08:01.516168Z","end":"2026-04-07T00:08:01.645029Z","steps":["trace[1228062708] 'read index received' (duration: 41.277985ms)","trace[1228062708] 'applied index is now lower than readState.Index' (duration: 87.581751ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:03.838072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003364164492024,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:08:03.986532Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.039018125s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T00:08:03.986988Z","caller":"traceutil/trace.go:171","msg":"trace[1048299757] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"1.039549032s","start":"2026-04-07T00:08:02.947411Z","end":"2026-04-07T00:08:03.98696Z","steps":["trace[1048299757] 'process raft request' (duration: 1.039354706s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:03.987158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:02.94737Z","time spent":"1.039697997s","remote":"127.0.0.1:53792","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-07T00:08:04.103329Z","caller":"traceutil/trace.go:171","msg":"trace[1744874357] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"765.420518ms","start":"2026-04-07T00:08:03.337876Z","end":"2026-04-07T00:08:04.103297Z","steps":["trace[1744874357] 'read index received' (duration: 649.717887ms)","trace[1744874357] 'applied index is now lower than readState.Index' (duration: 115.701011ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:04.103455Z","caller":"traceutil/trace.go:171","msg":"trace[1814639266] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"671.982367ms","start":"2026-04-07T00:08:03.431438Z","end":"2026-04-07T00:08:04.10342Z","steps":["trace[1814639266] 'process raft request' (duration: 598.529772ms)","trace[1814639266] 'compare' (duration: 73.161336ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:04.10359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:03.431415Z","time spent":"672.089041ms","remote":"127.0.0.1:53792","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-07T00:08:04.103628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.729083ms","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-07T00:08:04.103677Z","caller":"traceutil/trace.go:171","msg":"trace[477717434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1061; }","duration":"583.814186ms","start":"2026-04-07T00:08:03.519849Z","end":"2026-04-07T00:08:04.103663Z","steps":["trace[477717434] 'agreement among raft nodes before linearized reading' (duration: 583.6442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:04.103723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:03.519831Z","time spent":"583.877168ms","remote":"127.0.0.1:53792","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-07T00:08:04.103711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.152024ms","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-07T00:08:04.103797Z","caller":"traceutil/trace.go:171","msg":"trace[678845496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1061; }","duration":"454.249107ms","start":"2026-04-07T00:08:03.649524Z","end":"2026-04-07T00:08:04.103773Z","steps":["trace[678845496] 'agreement among raft nodes before linearized reading' (duration: 453.992439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:04.103793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.305799ms","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-07T00:08:04.103807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.276858ms","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-07T00:08:04.103863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:03.6495Z","time spent":"454.347411ms","remote":"127.0.0.1:53792","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-07T00:08:04.103947Z","caller":"traceutil/trace.go:171","msg":"trace[653114764] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1061; }","duration":"454.452534ms","start":"2026-04-07T00:08:03.649423Z","end":"2026-04-07T00:08:04.103875Z","steps":["trace[653114764] 'agreement among raft nodes before linearized reading' (duration: 454.190585ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:04.103963Z","caller":"traceutil/trace.go:171","msg":"trace[77858740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1061; }","duration":"454.404492ms","start":"2026-04-07T00:08:03.649481Z","end":"2026-04-07T00:08:04.103885Z","steps":["trace[77858740] 'agreement among raft nodes before linearized reading' (duration: 454.101232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:04.103924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.356401ms","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-07T00:08:04.104009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:03.649407Z","time spent":"454.588959ms","remote":"127.0.0.1:53792","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-07T00:08:04.104026Z","caller":"traceutil/trace.go:171","msg":"trace[1915349895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1061; }","duration":"454.505025ms","start":"2026-04-07T00:08:03.64951Z","end":"2026-04-07T00:08:04.104015Z","steps":["trace[1915349895] 'agreement among raft nodes before linearized reading' (duration: 454.318579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:04.104057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:03.649497Z","time spent":"454.552747ms","remote":"127.0.0.1:53792","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-07T00:08:04.104058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:03.649465Z","time spent":"454.534117ms","remote":"127.0.0.1:53792","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-07T00:08:04.104159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.282996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:04.104218Z","caller":"traceutil/trace.go:171","msg":"trace[1081720310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1061; }","duration":"766.383099ms","start":"2026-04-07T00:08:03.337821Z","end":"2026-04-07T00:08:04.104204Z","steps":["trace[1081720310] 'agreement among raft nodes before linearized reading' (duration: 765.645245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:04.104261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:03.337803Z","time spent":"766.449622ms","remote":"127.0.0.1:53516","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-07T00:08:04.30205Z","caller":"traceutil/trace.go:171","msg":"trace[2041954865] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"193.184496ms","start":"2026-04-07T00:08:04.108838Z","end":"2026-04-07T00:08:04.302022Z","steps":["trace[2041954865] 'process raft request' (duration: 181.937013ms)","trace[2041954865] 'compare' (duration: 10.851291ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:04.302179Z","caller":"traceutil/trace.go:171","msg":"trace[340607927] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"193.274299ms","start":"2026-04-07T00:08:04.108889Z","end":"2026-04-07T00:08:04.302163Z","steps":["trace[340607927] 'process raft request' (duration: 193.037171ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:04.302372Z","caller":"traceutil/trace.go:171","msg":"trace[727852153] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"193.421213ms","start":"2026-04-07T00:08:04.108939Z","end":"2026-04-07T00:08:04.302361Z","steps":["trace[727852153] 'process raft request' (duration: 193.372882ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:04.302407Z","caller":"traceutil/trace.go:171","msg":"trace[1670036875] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"193.456425ms","start":"2026-04-07T00:08:04.108922Z","end":"2026-04-07T00:08:04.302378Z","steps":["trace[1670036875] 'process raft request' (duration: 193.195296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:06.708184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:06.349333Z","time spent":"358.843703ms","remote":"127.0.0.1:37800","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-07T00:08:06.707837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.568965ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-07T00:08:06.708943Z","caller":"traceutil/trace.go:171","msg":"trace[1332388380] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1113; }","duration":"568.618975ms","start":"2026-04-07T00:08:06.140305Z","end":"2026-04-07T00:08:06.708924Z","steps":["trace[1332388380] 'read index received' (duration: 237.066945ms)","trace[1332388380] 'applied index is now lower than readState.Index' (duration: 331.55078ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:06.70903Z","caller":"traceutil/trace.go:171","msg":"trace[519231256] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"598.853633ms","start":"2026-04-07T00:08:06.110135Z","end":"2026-04-07T00:08:06.708989Z","steps":["trace[519231256] 'process raft request' (duration: 598.555393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:06.709239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:06.110115Z","time spent":"599.032938ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:08:06.709327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.992388ms","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-07T00:08:06.709422Z","caller":"traceutil/trace.go:171","msg":"trace[818170694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1067; }","duration":"569.152943ms","start":"2026-04-07T00:08:06.140254Z","end":"2026-04-07T00:08:06.709407Z","steps":["trace[818170694] 'agreement among raft nodes before linearized reading' (duration: 568.810472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:06.709461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:06.140234Z","time spent":"569.219265ms","remote":"127.0.0.1:53792","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-07T00:08:06.709552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.141037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-07T00:08:06.709632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.569859ms","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-07T00:08:06.70962Z","caller":"traceutil/trace.go:171","msg":"trace[869000365] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1067; }","duration":"404.274171ms","start":"2026-04-07T00:08:06.305334Z","end":"2026-04-07T00:08:06.709608Z","steps":["trace[869000365] 'agreement among raft nodes before linearized reading' (duration: 404.043544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:06.709757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:06.305315Z","time spent":"404.429146ms","remote":"127.0.0.1:53792","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-07T00:08:06.709769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.854796ms","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-07T00:08:06.709843Z","caller":"traceutil/trace.go:171","msg":"trace[197228681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1067; }","duration":"403.740954ms","start":"2026-04-07T00:08:06.305988Z","end":"2026-04-07T00:08:06.709729Z","steps":["trace[197228681] 'agreement among raft nodes before linearized reading' (duration: 403.246228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:06.709288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.436576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:06.709929Z","caller":"traceutil/trace.go:171","msg":"trace[1669396914] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1067; }","duration":"443.121438ms","start":"2026-04-07T00:08:06.266794Z","end":"2026-04-07T00:08:06.709915Z","steps":["trace[1669396914] 'agreement among raft nodes before linearized reading' (duration: 442.437536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:06.709978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:06.305966Z","time spent":"403.996723ms","remote":"127.0.0.1:53792","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-07T00:08:06.710008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:06.266778Z","time spent":"443.213831ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:08:06.709898Z","caller":"traceutil/trace.go:171","msg":"trace[1622444312] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1067; }","duration":"402.972039ms","start":"2026-04-07T00:08:06.306883Z","end":"2026-04-07T00:08:06.709855Z","steps":["trace[1622444312] 'agreement among raft nodes before linearized reading' (duration: 402.7033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:06.710083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:06.306875Z","time spent":"403.193156ms","remote":"127.0.0.1:53792","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-07T00:08:06.710394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.772407ms","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-07T00:08:06.710429Z","caller":"traceutil/trace.go:171","msg":"trace[1545083723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1067; }","duration":"404.85502ms","start":"2026-04-07T00:08:06.305563Z","end":"2026-04-07T00:08:06.710418Z","steps":["trace[1545083723] 'agreement among raft nodes before linearized reading' (duration: 403.672592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:06.710456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:06.305526Z","time spent":"404.923652ms","remote":"127.0.0.1:53792","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-07T00:08:06.9451Z","caller":"traceutil/trace.go:171","msg":"trace[1764143180] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"212.78277ms","start":"2026-04-07T00:08:06.732292Z","end":"2026-04-07T00:08:06.945075Z","steps":["trace[1764143180] 'process raft request' (duration: 212.695948ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:06.945137Z","caller":"traceutil/trace.go:171","msg":"trace[424269474] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"212.847652ms","start":"2026-04-07T00:08:06.732235Z","end":"2026-04-07T00:08:06.945083Z","steps":["trace[424269474] 'process raft request' (duration: 202.078973ms)","trace[424269474] 'compare' (duration: 10.49871ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:06.945357Z","caller":"traceutil/trace.go:171","msg":"trace[927501309] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"212.750879ms","start":"2026-04-07T00:08:06.732595Z","end":"2026-04-07T00:08:06.945346Z","steps":["trace[927501309] 'process raft request' (duration: 212.439039ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:06.945504Z","caller":"traceutil/trace.go:171","msg":"trace[1464894561] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"212.46927ms","start":"2026-04-07T00:08:06.733014Z","end":"2026-04-07T00:08:06.945484Z","steps":["trace[1464894561] 'process raft request' (duration: 212.291784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:19.456516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.758528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:19.456603Z","caller":"traceutil/trace.go:171","msg":"trace[917934892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1164; }","duration":"117.897932ms","start":"2026-04-07T00:08:19.338686Z","end":"2026-04-07T00:08:19.456584Z","steps":["trace[917934892] 'range keys from in-memory index tree' (duration: 117.666304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:21.336818Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003364164492250,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:08:21.737612Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.254420819s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T00:08:21.737861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:20.483087Z","time spent":"1.254770761s","remote":"127.0.0.1:53562","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-07T00:08:22.493611Z","caller":"traceutil/trace.go:171","msg":"trace[864797643] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"1.717647957s","start":"2026-04-07T00:08:20.775923Z","end":"2026-04-07T00:08:22.493571Z","steps":["trace[864797643] 'process raft request' (duration: 1.707693795s)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:22.493757Z","caller":"traceutil/trace.go:171","msg":"trace[942693155] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1216; }","duration":"1.657567304s","start":"2026-04-07T00:08:20.836161Z","end":"2026-04-07T00:08:22.493728Z","steps":["trace[942693155] 'read index received' (duration: 901.596111ms)","trace[942693155] 'applied index is now lower than readState.Index' (duration: 755.969643ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:22.493796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:20.77589Z","time spent":"1.717821283s","remote":"127.0.0.1:53792","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-07T00:08:22.494317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158322152s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:08:22.494362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.489649595s","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-07T00:08:22.494363Z","caller":"traceutil/trace.go:171","msg":"trace[1331859014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1166; }","duration":"1.158411935s","start":"2026-04-07T00:08:21.335939Z","end":"2026-04-07T00:08:22.494351Z","steps":["trace[1331859014] 'agreement among raft nodes before linearized reading' (duration: 1.158340373s)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:22.49439Z","caller":"traceutil/trace.go:171","msg":"trace[1981518934] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1166; }","duration":"1.489720617s","start":"2026-04-07T00:08:21.004662Z","end":"2026-04-07T00:08:22.494383Z","steps":["trace[1981518934] 'agreement among raft nodes before linearized reading' (duration: 1.489595543s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:22.4944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:21.335919Z","time spent":"1.158475098s","remote":"127.0.0.1:53516","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-07T00:08:22.494411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:21.004642Z","time spent":"1.489764708s","remote":"127.0.0.1:53792","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":"info","ts":"2026-04-07T00:08:22.49439Z","caller":"traceutil/trace.go:171","msg":"trace[1091557953] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"755.532199ms","start":"2026-04-07T00:08:21.738807Z","end":"2026-04-07T00:08:22.494339Z","steps":["trace[1091557953] 'process raft request' (duration: 754.726753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:22.494437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.487772425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-07T00:08:22.494461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.658280397s","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-07T00:08:22.494493Z","caller":"traceutil/trace.go:171","msg":"trace[247851328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1166; }","duration":"1.487858967s","start":"2026-04-07T00:08:21.006617Z","end":"2026-04-07T00:08:22.494476Z","steps":["trace[247851328] 'agreement among raft nodes before linearized reading' (duration: 1.487708713s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:22.494536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:21.006601Z","time spent":"1.487925719s","remote":"127.0.0.1:53792","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-07T00:08:22.494537Z","caller":"traceutil/trace.go:171","msg":"trace[615199887] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1166; }","duration":"1.658410212s","start":"2026-04-07T00:08:20.836106Z","end":"2026-04-07T00:08:22.494516Z","steps":["trace[615199887] 'agreement among raft nodes before linearized reading' (duration: 1.657760291s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:22.494555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.486437051s","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-07T00:08:22.494588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:20.836088Z","time spent":"1.658491364s","remote":"127.0.0.1:53792","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-07T00:08:22.494598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:21.738781Z","time spent":"755.696743ms","remote":"127.0.0.1:53562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:08:22.494548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488646253s","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-07T00:08:22.494627Z","caller":"traceutil/trace.go:171","msg":"trace[1902235820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1166; }","duration":"1.486546715s","start":"2026-04-07T00:08:21.008061Z","end":"2026-04-07T00:08:22.494608Z","steps":["trace[1902235820] 'agreement among raft nodes before linearized reading' (duration: 1.486329288s)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:22.494645Z","caller":"traceutil/trace.go:171","msg":"trace[900760008] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1166; }","duration":"1.488782047s","start":"2026-04-07T00:08:21.005857Z","end":"2026-04-07T00:08:22.49464Z","steps":["trace[900760008] 'agreement among raft nodes before linearized reading' (duration: 1.488658773s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:22.494666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:21.005836Z","time spent":"1.488826398s","remote":"127.0.0.1:53792","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-07T00:08:22.494709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:21.008048Z","time spent":"1.486642867s","remote":"127.0.0.1:53792","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-07T00:08:35.141483Z","caller":"traceutil/trace.go:171","msg":"trace[1927221253] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1260; }","duration":"442.799387ms","start":"2026-04-07T00:08:34.698648Z","end":"2026-04-07T00:08:35.141447Z","steps":["trace[1927221253] 'read index received' (duration: 440.10731ms)","trace[1927221253] 'applied index is now lower than readState.Index' (duration: 2.690947ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:35.141638Z","caller":"traceutil/trace.go:171","msg":"trace[375885779] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"466.09121ms","start":"2026-04-07T00:08:34.675536Z","end":"2026-04-07T00:08:35.141627Z","steps":["trace[375885779] 'process raft request' (duration: 463.266858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:35.141745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:34.675515Z","time spent":"466.148382ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:08:35.141846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.416828ms","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-07T00:08:35.141935Z","caller":"traceutil/trace.go:171","msg":"trace[1388419584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1206; }","duration":"409.555842ms","start":"2026-04-07T00:08:34.732361Z","end":"2026-04-07T00:08:35.141917Z","steps":["trace[1388419584] 'agreement among raft nodes before linearized reading' (duration: 409.324705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:35.141975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:34.732341Z","time spent":"409.625285ms","remote":"127.0.0.1:53792","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-07T00:08:35.142087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.771845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:08:35.142109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.899278ms","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-07T00:08:35.142142Z","caller":"traceutil/trace.go:171","msg":"trace[603753606] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1206; }","duration":"197.896849ms","start":"2026-04-07T00:08:34.944234Z","end":"2026-04-07T00:08:35.142131Z","steps":["trace[603753606] 'agreement among raft nodes before linearized reading' (duration: 197.797826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:35.142128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.476329ms","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-07T00:08:35.142182Z","caller":"traceutil/trace.go:171","msg":"trace[320873426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1206; }","duration":"352.008512ms","start":"2026-04-07T00:08:34.790156Z","end":"2026-04-07T00:08:35.142165Z","steps":["trace[320873426] 'agreement among raft nodes before linearized reading' (duration: 351.794815ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:35.142193Z","caller":"traceutil/trace.go:171","msg":"trace[30962857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1206; }","duration":"443.578802ms","start":"2026-04-07T00:08:34.698598Z","end":"2026-04-07T00:08:35.142177Z","steps":["trace[30962857] 'agreement among raft nodes before linearized reading' (duration: 443.425627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:35.142223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:34.790146Z","time spent":"352.067694ms","remote":"127.0.0.1:53792","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-07T00:08:35.142241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:34.698579Z","time spent":"443.653004ms","remote":"127.0.0.1:53792","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-07T00:08:35.142418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.771559ms","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-07T00:08:35.142444Z","caller":"traceutil/trace.go:171","msg":"trace[2034999416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1206; }","duration":"409.834591ms","start":"2026-04-07T00:08:34.732603Z","end":"2026-04-07T00:08:35.142438Z","steps":["trace[2034999416] 'agreement among raft nodes before linearized reading' (duration: 409.399667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:35.142458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:34.73258Z","time spent":"409.873622ms","remote":"127.0.0.1:53792","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-07T00:08:35.142628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.89691ms","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-07T00:08:35.142672Z","caller":"traceutil/trace.go:171","msg":"trace[688017959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1206; }","duration":"356.973392ms","start":"2026-04-07T00:08:34.785688Z","end":"2026-04-07T00:08:35.142661Z","steps":["trace[688017959] 'agreement among raft nodes before linearized reading' (duration: 356.30163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:35.142707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:34.78567Z","time spent":"357.030074ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T00:08:40.696946Z","caller":"traceutil/trace.go:171","msg":"trace[425797709] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"123.149252ms","start":"2026-04-07T00:08:40.573663Z","end":"2026-04-07T00:08:40.696812Z","steps":["trace[425797709] 'process raft request' (duration: 123.018108ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:43.34837Z","caller":"traceutil/trace.go:171","msg":"trace[1716015392] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"127.12276ms","start":"2026-04-07T00:08:43.221196Z","end":"2026-04-07T00:08:43.348318Z","steps":["trace[1716015392] 'read index received' (duration: 127.038367ms)","trace[1716015392] 'applied index is now lower than readState.Index' (duration: 83.723µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:43.348434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.220893ms","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-07T00:08:43.348621Z","caller":"traceutil/trace.go:171","msg":"trace[221035633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1266; }","duration":"127.470662ms","start":"2026-04-07T00:08:43.22114Z","end":"2026-04-07T00:08:43.34861Z","steps":["trace[221035633] 'agreement among raft nodes before linearized reading' (duration: 127.189872ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:43.348632Z","caller":"traceutil/trace.go:171","msg":"trace[577571797] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"644.818619ms","start":"2026-04-07T00:08:42.703765Z","end":"2026-04-07T00:08:43.348584Z","steps":["trace[577571797] 'process raft request' (duration: 644.457127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:43.348818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:42.703743Z","time spent":"644.980484ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:08:44.471127Z","caller":"traceutil/trace.go:171","msg":"trace[1006448336] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"416.425064ms","start":"2026-04-07T00:08:44.054678Z","end":"2026-04-07T00:08:44.471103Z","steps":["trace[1006448336] 'process raft request' (duration: 416.30092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:44.471479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:44.054657Z","time spent":"416.544378ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:08:44.958141Z","caller":"traceutil/trace.go:171","msg":"trace[202883363] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1331; }","duration":"621.731832ms","start":"2026-04-07T00:08:44.336382Z","end":"2026-04-07T00:08:44.958114Z","steps":["trace[202883363] 'read index received' (duration: 134.695365ms)","trace[202883363] 'applied index is now lower than readState.Index' (duration: 487.034427ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:44.958173Z","caller":"traceutil/trace.go:171","msg":"trace[1145191221] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"854.662392ms","start":"2026-04-07T00:08:44.103478Z","end":"2026-04-07T00:08:44.958141Z","steps":["trace[1145191221] 'process raft request' (duration: 854.359653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:44.958369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:44.103452Z","time spent":"854.800377ms","remote":"127.0.0.1:53598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:08:44.958425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.00955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:44.958511Z","caller":"traceutil/trace.go:171","msg":"trace[82626734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"622.134455ms","start":"2026-04-07T00:08:44.336354Z","end":"2026-04-07T00:08:44.958488Z","steps":["trace[82626734] 'agreement among raft nodes before linearized reading' (duration: 621.881727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:44.958553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:44.336338Z","time spent":"622.205877ms","remote":"127.0.0.1:53516","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-07T00:08:45.241135Z","caller":"traceutil/trace.go:171","msg":"trace[1289728487] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"278.517515ms","start":"2026-04-07T00:08:44.962604Z","end":"2026-04-07T00:08:45.241122Z","steps":["trace[1289728487] 'process raft request' (duration: 278.478164ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:45.241109Z","caller":"traceutil/trace.go:171","msg":"trace[1203040685] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"759.108834ms","start":"2026-04-07T00:08:44.481968Z","end":"2026-04-07T00:08:45.241076Z","steps":["trace[1203040685] 'process raft request' (duration: 736.287196ms)","trace[1203040685] 'compare' (duration: 22.57385ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:45.241293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:44.481955Z","time spent":"759.258338ms","remote":"127.0.0.1:53598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:08:45.241453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.352297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-04-07T00:08:45.241521Z","caller":"traceutil/trace.go:171","msg":"trace[1529714232] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1278; }","duration":"766.463331ms","start":"2026-04-07T00:08:44.475042Z","end":"2026-04-07T00:08:45.241506Z","steps":["trace[1529714232] 'agreement among raft nodes before linearized reading' (duration: 766.201042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:45.241566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:44.475024Z","time spent":"766.532243ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-07T00:08:45.241206Z","caller":"traceutil/trace.go:171","msg":"trace[965691314] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1333; }","duration":"282.923348ms","start":"2026-04-07T00:08:44.958211Z","end":"2026-04-07T00:08:45.241134Z","steps":["trace[965691314] 'read index received' (duration: 260.172722ms)","trace[965691314] 'applied index is now lower than readState.Index' (duration: 22.747925ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:45.24201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.107511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:45.242042Z","caller":"traceutil/trace.go:171","msg":"trace[1842364526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1278; }","duration":"282.178423ms","start":"2026-04-07T00:08:44.959853Z","end":"2026-04-07T00:08:45.242031Z","steps":["trace[1842364526] 'agreement among raft nodes before linearized reading' (duration: 282.123192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:46.370947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.357125ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-07T00:08:46.3711Z","caller":"traceutil/trace.go:171","msg":"trace[1226176616] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1343; }","duration":"507.769528ms","start":"2026-04-07T00:08:45.863316Z","end":"2026-04-07T00:08:46.371086Z","steps":["trace[1226176616] 'read index received' (duration: 63.143µs)","trace[1226176616] 'applied index is now lower than readState.Index' (duration: 507.704855ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:46.371522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.200452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:46.371583Z","caller":"traceutil/trace.go:171","msg":"trace[484247686] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1286; }","duration":"508.270724ms","start":"2026-04-07T00:08:45.863302Z","end":"2026-04-07T00:08:46.371573Z","steps":["trace[484247686] 'agreement among raft nodes before linearized reading' (duration: 508.178001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:46.371626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:45.863245Z","time spent":"508.371458ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:08:46.656366Z","caller":"traceutil/trace.go:171","msg":"trace[2085868139] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"214.051101ms","start":"2026-04-07T00:08:46.442288Z","end":"2026-04-07T00:08:46.656339Z","steps":["trace[2085868139] 'process raft request' (duration: 213.845355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:46.806284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.37558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:08:46.806384Z","caller":"traceutil/trace.go:171","msg":"trace[1755087897] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1287; }","duration":"132.520605ms","start":"2026-04-07T00:08:46.673837Z","end":"2026-04-07T00:08:46.806358Z","steps":["trace[1755087897] 'count revisions from in-memory index tree' (duration: 132.287308ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:47.6219Z","caller":"traceutil/trace.go:171","msg":"trace[130997585] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"206.760405ms","start":"2026-04-07T00:08:47.415115Z","end":"2026-04-07T00:08:47.621875Z","steps":["trace[130997585] 'read index received' (duration: 206.549658ms)","trace[130997585] 'applied index is now lower than readState.Index' (duration: 209.347µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:47.621974Z","caller":"traceutil/trace.go:171","msg":"trace[1102534289] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"224.22911ms","start":"2026-04-07T00:08:47.397712Z","end":"2026-04-07T00:08:47.621941Z","steps":["trace[1102534289] 'process raft request' (duration: 224.007033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:47.622114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.990113ms","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-07T00:08:47.622154Z","caller":"traceutil/trace.go:171","msg":"trace[1310289750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1288; }","duration":"207.087506ms","start":"2026-04-07T00:08:47.415055Z","end":"2026-04-07T00:08:47.622143Z","steps":["trace[1310289750] 'agreement among raft nodes before linearized reading' (duration: 206.947741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:47.62231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.025137ms","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-07T00:08:47.622356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.409566ms","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-07T00:08:47.62241Z","caller":"traceutil/trace.go:171","msg":"trace[545524849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1288; }","duration":"181.498259ms","start":"2026-04-07T00:08:47.440896Z","end":"2026-04-07T00:08:47.622394Z","steps":["trace[545524849] 'agreement among raft nodes before linearized reading' (duration: 181.307153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:47.622495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.048108ms","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-07T00:08:47.622538Z","caller":"traceutil/trace.go:171","msg":"trace[1874476642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1288; }","duration":"174.106339ms","start":"2026-04-07T00:08:47.448418Z","end":"2026-04-07T00:08:47.622524Z","steps":["trace[1874476642] 'agreement among raft nodes before linearized reading' (duration: 174.020336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:47.622604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.473601ms","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-07T00:08:47.622344Z","caller":"traceutil/trace.go:171","msg":"trace[884098553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1288; }","duration":"174.090179ms","start":"2026-04-07T00:08:47.448246Z","end":"2026-04-07T00:08:47.622336Z","steps":["trace[884098553] 'agreement among raft nodes before linearized reading' (duration: 174.008356ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:47.622658Z","caller":"traceutil/trace.go:171","msg":"trace[1589751448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1288; }","duration":"174.557244ms","start":"2026-04-07T00:08:47.448087Z","end":"2026-04-07T00:08:47.622644Z","steps":["trace[1589751448] 'agreement among raft nodes before linearized reading' (duration: 174.43289ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:51.512663Z","caller":"traceutil/trace.go:171","msg":"trace[2126885855] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1381; }","duration":"216.053266ms","start":"2026-04-07T00:08:51.296587Z","end":"2026-04-07T00:08:51.51264Z","steps":["trace[2126885855] 'read index received' (duration: 215.725255ms)","trace[2126885855] 'applied index is now lower than readState.Index' (duration: 326.911µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:51.512781Z","caller":"traceutil/trace.go:171","msg":"trace[304376543] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"484.230277ms","start":"2026-04-07T00:08:51.028541Z","end":"2026-04-07T00:08:51.512771Z","steps":["trace[304376543] 'process raft request' (duration: 483.783663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:51.512958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:51.028525Z","time spent":"484.281178ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:08:51.512961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.837828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:51.513028Z","caller":"traceutil/trace.go:171","msg":"trace[1901121156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1323; }","duration":"176.953822ms","start":"2026-04-07T00:08:51.336058Z","end":"2026-04-07T00:08:51.513011Z","steps":["trace[1901121156] 'agreement among raft nodes before linearized reading' (duration: 176.798057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:51.513053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.437899ms","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-07T00:08:51.513136Z","caller":"traceutil/trace.go:171","msg":"trace[314211584] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1323; }","duration":"216.596464ms","start":"2026-04-07T00:08:51.296515Z","end":"2026-04-07T00:08:51.513111Z","steps":["trace[314211584] 'agreement among raft nodes before linearized reading' (duration: 216.415488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:52.150088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003364164492575,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T00:08:52.395289Z","caller":"traceutil/trace.go:171","msg":"trace[1109545507] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1382; }","duration":"745.971449ms","start":"2026-04-07T00:08:51.64929Z","end":"2026-04-07T00:08:52.395261Z","steps":["trace[1109545507] 'read index received' (duration: 691.822608ms)","trace[1109545507] 'applied index is now lower than readState.Index' (duration: 54.147491ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:52.395308Z","caller":"traceutil/trace.go:171","msg":"trace[1190345310] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"759.960051ms","start":"2026-04-07T00:08:51.635318Z","end":"2026-04-07T00:08:52.395278Z","steps":["trace[1190345310] 'process raft request' (duration: 705.79396ms)","trace[1190345310] 'compare' (duration: 53.532041ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:52.395459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:51.635293Z","time spent":"760.076735ms","remote":"127.0.0.1:53792","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-07T00:08:52.395549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.839055ms","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-07T00:08:52.395591Z","caller":"traceutil/trace.go:171","msg":"trace[1726031252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1324; }","duration":"741.938408ms","start":"2026-04-07T00:08:51.653641Z","end":"2026-04-07T00:08:52.39558Z","steps":["trace[1726031252] 'agreement among raft nodes before linearized reading' (duration: 741.760342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:52.395626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:51.65362Z","time spent":"741.99749ms","remote":"127.0.0.1:53792","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-07T00:08:52.395772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.991033ms","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-07T00:08:52.395836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.933271ms","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-07T00:08:52.395837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.996465ms","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-07T00:08:52.395844Z","caller":"traceutil/trace.go:171","msg":"trace[446833612] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1324; }","duration":"739.090456ms","start":"2026-04-07T00:08:51.656736Z","end":"2026-04-07T00:08:52.395827Z","steps":["trace[446833612] 'agreement among raft nodes before linearized reading' (duration: 738.862899ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:52.395871Z","caller":"traceutil/trace.go:171","msg":"trace[1093833845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1324; }","duration":"738.982863ms","start":"2026-04-07T00:08:51.656879Z","end":"2026-04-07T00:08:52.395862Z","steps":["trace[1093833845] 'agreement among raft nodes before linearized reading' (duration: 738.8844ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:52.395878Z","caller":"traceutil/trace.go:171","msg":"trace[1653959437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1324; }","duration":"740.076988ms","start":"2026-04-07T00:08:51.65579Z","end":"2026-04-07T00:08:52.395867Z","steps":["trace[1653959437] 'agreement among raft nodes before linearized reading' (duration: 739.937614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:52.395899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:51.656841Z","time spent":"739.052015ms","remote":"127.0.0.1:53792","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-07T00:08:52.395889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:51.656692Z","time spent":"739.18549ms","remote":"127.0.0.1:53792","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-07T00:08:52.395941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:51.65577Z","time spent":"740.16348ms","remote":"127.0.0.1:53792","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-07T00:08:52.396119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.816427ms","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-07T00:08:52.396181Z","caller":"traceutil/trace.go:171","msg":"trace[484342325] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1324; }","duration":"746.91915ms","start":"2026-04-07T00:08:51.649245Z","end":"2026-04-07T00:08:52.396165Z","steps":["trace[484342325] 'agreement among raft nodes before linearized reading' (duration: 746.136355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:52.39623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:51.649232Z","time spent":"746.984792ms","remote":"127.0.0.1:53792","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-07T00:08:52.396717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.415887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-07T00:08:52.39679Z","caller":"traceutil/trace.go:171","msg":"trace[1877059481] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1324; }","duration":"735.50272ms","start":"2026-04-07T00:08:51.661265Z","end":"2026-04-07T00:08:52.396767Z","steps":["trace[1877059481] 'agreement among raft nodes before linearized reading' (duration: 734.171537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:52.396873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:51.661254Z","time spent":"735.603993ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-07T00:08:53.218242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.03102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:08:53.218356Z","caller":"traceutil/trace.go:171","msg":"trace[1741029487] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"817.10996ms","start":"2026-04-07T00:08:52.401227Z","end":"2026-04-07T00:08:53.218337Z","steps":["trace[1741029487] 'process raft request' (duration: 225.894554ms)","trace[1741029487] 'compare' (duration: 590.884245ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:53.218416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:52.401205Z","time spent":"817.181791ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:08:53.22025Z","caller":"traceutil/trace.go:171","msg":"trace[169052768] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"818.159493ms","start":"2026-04-07T00:08:52.402065Z","end":"2026-04-07T00:08:53.220224Z","steps":["trace[169052768] 'process raft request' (duration: 817.993818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:53.220391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:52.402019Z","time spent":"818.298978ms","remote":"127.0.0.1:53792","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-07T00:08:53.220617Z","caller":"traceutil/trace.go:171","msg":"trace[2092129160] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"818.186485ms","start":"2026-04-07T00:08:52.402397Z","end":"2026-04-07T00:08:53.220583Z","steps":["trace[2092129160] 'process raft request' (duration: 817.785872ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:53.220658Z","caller":"traceutil/trace.go:171","msg":"trace[1320210193] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"817.582685ms","start":"2026-04-07T00:08:52.403042Z","end":"2026-04-07T00:08:53.220625Z","steps":["trace[1320210193] 'process raft request' (duration: 817.544854ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:53.220729Z","caller":"traceutil/trace.go:171","msg":"trace[1894946749] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"817.717769ms","start":"2026-04-07T00:08:52.402997Z","end":"2026-04-07T00:08:53.220714Z","steps":["trace[1894946749] 'process raft request' (duration: 817.555314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:53.220783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:52.402348Z","time spent":"818.348229ms","remote":"127.0.0.1:53792","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-07T00:08:53.220804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:52.402991Z","time spent":"817.778792ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:08:53.220835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:52.403038Z","time spent":"817.702719ms","remote":"127.0.0.1:53792","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-07T00:08:53.220667Z","caller":"traceutil/trace.go:171","msg":"trace[222905171] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"817.711199ms","start":"2026-04-07T00:08:52.402914Z","end":"2026-04-07T00:08:53.220626Z","steps":["trace[222905171] 'process raft request' (duration: 817.533433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:53.22097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:52.402869Z","time spent":"818.051551ms","remote":"127.0.0.1:53792","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-07T00:09:05.496278Z","caller":"traceutil/trace.go:171","msg":"trace[813323983] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"151.848486ms","start":"2026-04-07T00:09:05.344391Z","end":"2026-04-07T00:09:05.496239Z","steps":["trace[813323983] 'process raft request' (duration: 111.986564ms)","trace[813323983] 'compare' (duration: 39.616794ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:09:35.759318Z","caller":"traceutil/trace.go:171","msg":"trace[2015860670] transaction","detail":"{read_only:false; response_revision:1521; number_of_response:1; }","duration":"122.843284ms","start":"2026-04-07T00:09:35.636424Z","end":"2026-04-07T00:09:35.759267Z","steps":["trace[2015860670] 'process raft request' (duration: 122.697039ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:35.785989Z","caller":"traceutil/trace.go:171","msg":"trace[1501780374] linearizableReadLoop","detail":"{readStateIndex:1589; appliedIndex:1587; }","duration":"143.52475ms","start":"2026-04-07T00:09:35.642433Z","end":"2026-04-07T00:09:35.785958Z","steps":["trace[1501780374] 'read index received' (duration: 116.786323ms)","trace[1501780374] 'applied index is now lower than readState.Index' (duration: 26.737267ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:09:35.786123Z","caller":"traceutil/trace.go:171","msg":"trace[714643649] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"149.608381ms","start":"2026-04-07T00:09:35.636502Z","end":"2026-04-07T00:09:35.78611Z","steps":["trace[714643649] 'process raft request' (duration: 149.334882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:35.786596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.13916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-07T00:09:35.786672Z","caller":"traceutil/trace.go:171","msg":"trace[1089256030] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1522; }","duration":"144.247804ms","start":"2026-04-07T00:09:35.642404Z","end":"2026-04-07T00:09:35.786651Z","steps":["trace[1089256030] 'agreement among raft nodes before linearized reading' (duration: 144.034727ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:35.929137Z","caller":"traceutil/trace.go:171","msg":"trace[753503767] transaction","detail":"{read_only:false; response_revision:1524; number_of_response:1; }","duration":"137.973116ms","start":"2026-04-07T00:09:35.791135Z","end":"2026-04-07T00:09:35.929108Z","steps":["trace[753503767] 'process raft request' (duration: 137.654975ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:35.929195Z","caller":"traceutil/trace.go:171","msg":"trace[338448570] transaction","detail":"{read_only:false; response_revision:1525; number_of_response:1; }","duration":"136.60251ms","start":"2026-04-07T00:09:35.792548Z","end":"2026-04-07T00:09:35.929151Z","steps":["trace[338448570] 'process raft request' (duration: 136.435515ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:11.870347Z","caller":"traceutil/trace.go:171","msg":"trace[1561798292] transaction","detail":"{read_only:false; response_revision:1687; number_of_response:1; }","duration":"131.443269ms","start":"2026-04-07T00:10:11.738868Z","end":"2026-04-07T00:10:11.870311Z","steps":["trace[1561798292] 'process raft request' (duration: 131.17731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:12.078763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.377221ms","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-07T00:10:12.078905Z","caller":"traceutil/trace.go:171","msg":"trace[1221626889] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1687; }","duration":"106.596918ms","start":"2026-04-07T00:10:11.972279Z","end":"2026-04-07T00:10:12.078875Z","steps":["trace[1221626889] 'range keys from in-memory index tree' (duration: 106.111833ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:13.148749Z","caller":"traceutil/trace.go:171","msg":"trace[1191323015] transaction","detail":"{read_only:false; response_revision:1693; number_of_response:1; }","duration":"108.458696ms","start":"2026-04-07T00:10:13.040265Z","end":"2026-04-07T00:10:13.148724Z","steps":["trace[1191323015] 'process raft request' (duration: 108.305211ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:22.463628Z","caller":"traceutil/trace.go:171","msg":"trace[1645327214] transaction","detail":"{read_only:false; response_revision:1736; number_of_response:1; }","duration":"160.398248ms","start":"2026-04-07T00:10:22.303194Z","end":"2026-04-07T00:10:22.463592Z","steps":["trace[1645327214] 'process raft request' (duration: 96.434669ms)","trace[1645327214] 'compare' (duration: 63.690131ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:22.634619Z","caller":"traceutil/trace.go:171","msg":"trace[1842307864] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"165.796998ms","start":"2026-04-07T00:10:22.468788Z","end":"2026-04-07T00:10:22.634585Z","steps":["trace[1842307864] 'process raft request' (duration: 157.931631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:43.459716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.420015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:11:43.459888Z","caller":"traceutil/trace.go:171","msg":"trace[454419159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2107; }","duration":"123.659582ms","start":"2026-04-07T00:11:43.336208Z","end":"2026-04-07T00:11:43.459867Z","steps":["trace[454419159] 'range keys from in-memory index tree' (duration: 123.339872ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:01.95618Z","caller":"traceutil/trace.go:171","msg":"trace[1942381040] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"130.22889ms","start":"2026-04-07T00:12:01.825906Z","end":"2026-04-07T00:12:01.956135Z","steps":["trace[1942381040] 'process raft request' (duration: 130.017433ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:07.469685Z","caller":"traceutil/trace.go:171","msg":"trace[1033211197] linearizableReadLoop","detail":"{readStateIndex:2343; appliedIndex:2342; }","duration":"135.807707ms","start":"2026-04-07T00:12:07.333857Z","end":"2026-04-07T00:12:07.469665Z","steps":["trace[1033211197] 'read index received' (duration: 135.626751ms)","trace[1033211197] 'applied index is now lower than readState.Index' (duration: 180.096µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:12:07.469764Z","caller":"traceutil/trace.go:171","msg":"trace[393216043] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"151.70395ms","start":"2026-04-07T00:12:07.318028Z","end":"2026-04-07T00:12:07.469732Z","steps":["trace[393216043] 'process raft request' (duration: 151.498084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:07.469826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.952282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:12:07.470034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.165087ms","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-07T00:12:07.470112Z","caller":"traceutil/trace.go:171","msg":"trace[1370436586] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2244; }","duration":"105.275131ms","start":"2026-04-07T00:12:07.364823Z","end":"2026-04-07T00:12:07.470098Z","steps":["trace[1370436586] 'agreement among raft nodes before linearized reading' (duration: 105.042554ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:07.470247Z","caller":"traceutil/trace.go:171","msg":"trace[120698431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2244; }","duration":"136.021264ms","start":"2026-04-07T00:12:07.333829Z","end":"2026-04-07T00:12:07.46985Z","steps":["trace[120698431] 'agreement among raft nodes before linearized reading' (duration: 135.958822ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:08.108594Z","caller":"traceutil/trace.go:171","msg":"trace[916330311] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"121.311388ms","start":"2026-04-07T00:12:07.98726Z","end":"2026-04-07T00:12:08.108571Z","steps":["trace[916330311] 'process raft request' (duration: 121.175814ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:09.025183Z","caller":"traceutil/trace.go:171","msg":"trace[1185137323] linearizableReadLoop","detail":"{readStateIndex:2348; appliedIndex:2347; }","duration":"112.457989ms","start":"2026-04-07T00:12:08.912696Z","end":"2026-04-07T00:12:09.025154Z","steps":["trace[1185137323] 'read index received' (duration: 112.333745ms)","trace[1185137323] 'applied index is now lower than readState.Index' (duration: 123.363µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:12:09.025221Z","caller":"traceutil/trace.go:171","msg":"trace[1242858215] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"252.731737ms","start":"2026-04-07T00:12:08.77246Z","end":"2026-04-07T00:12:09.025191Z","steps":["trace[1242858215] 'process raft request' (duration: 252.537401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:09.026478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.76953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T00:12:09.026538Z","caller":"traceutil/trace.go:171","msg":"trace[1278028521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2249; }","duration":"113.880234ms","start":"2026-04-07T00:12:08.912644Z","end":"2026-04-07T00:12:09.026524Z","steps":["trace[1278028521] 'agreement among raft nodes before linearized reading' (duration: 112.642115ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:09.72149Z","caller":"traceutil/trace.go:171","msg":"trace[1398132865] linearizableReadLoop","detail":"{readStateIndex:2353; appliedIndex:2352; }","duration":"246.84014ms","start":"2026-04-07T00:12:09.474619Z","end":"2026-04-07T00:12:09.721459Z","steps":["trace[1398132865] 'read index received' (duration: 216.602744ms)","trace[1398132865] 'applied index is now lower than readState.Index' (duration: 30.233876ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:12:09.721548Z","caller":"traceutil/trace.go:171","msg":"trace[964859613] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"371.68986ms","start":"2026-04-07T00:12:09.349833Z","end":"2026-04-07T00:12:09.721523Z","steps":["trace[964859613] 'process raft request' (duration: 341.472804ms)","trace[964859613] 'compare' (duration: 29.993249ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:12:09.722385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:12:09.34982Z","time spent":"372.464735ms","remote":"127.0.0.1:53792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:12:09.722732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.456769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-07T00:12:09.722805Z","caller":"traceutil/trace.go:171","msg":"trace[278256] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2254; }","duration":"167.620243ms","start":"2026-04-07T00:12:09.555164Z","end":"2026-04-07T00:12:09.722784Z","steps":["trace[278256] 'agreement among raft nodes before linearized reading' (duration: 167.385006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:09.722799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.659838ms","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-07T00:12:09.722913Z","caller":"traceutil/trace.go:171","msg":"trace[509412853] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2254; }","duration":"175.827293ms","start":"2026-04-07T00:12:09.547066Z","end":"2026-04-07T00:12:09.722894Z","steps":["trace[509412853] 'agreement among raft nodes before linearized reading' (duration: 175.488792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:09.722986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.345068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T00:12:09.723068Z","caller":"traceutil/trace.go:171","msg":"trace[942684954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2254; }","duration":"248.474602ms","start":"2026-04-07T00:12:09.474576Z","end":"2026-04-07T00:12:09.723051Z","steps":["trace[942684954] 'agreement among raft nodes before linearized reading' (duration: 247.039906ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:19.501747Z","caller":"traceutil/trace.go:171","msg":"trace[853374157] linearizableReadLoop","detail":"{readStateIndex:2405; appliedIndex:2404; }","duration":"112.582372ms","start":"2026-04-07T00:12:19.389145Z","end":"2026-04-07T00:12:19.501727Z","steps":["trace[853374157] 'read index received' (duration: 112.478829ms)","trace[853374157] 'applied index is now lower than readState.Index' (duration: 102.933µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:12:19.501867Z","caller":"traceutil/trace.go:171","msg":"trace[1246507188] transaction","detail":"{read_only:false; response_revision:2303; number_of_response:1; }","duration":"135.254279ms","start":"2026-04-07T00:12:19.36659Z","end":"2026-04-07T00:12:19.501844Z","steps":["trace[1246507188] 'process raft request' (duration: 135.024042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:19.501932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.771888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:1 size:1107"} {"level":"info","ts":"2026-04-07T00:12:19.501961Z","caller":"traceutil/trace.go:171","msg":"trace[858060019] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:1; response_revision:2303; }","duration":"112.837581ms","start":"2026-04-07T00:12:19.389114Z","end":"2026-04-07T00:12:19.501951Z","steps":["trace[858060019] 'agreement among raft nodes before linearized reading' (duration: 112.718017ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:40.0765Z","caller":"traceutil/trace.go:171","msg":"trace[2141702284] linearizableReadLoop","detail":"{readStateIndex:2505; appliedIndex:2504; }","duration":"271.590224ms","start":"2026-04-07T00:12:39.804882Z","end":"2026-04-07T00:12:40.076472Z","steps":["trace[2141702284] 'read index received' (duration: 271.380947ms)","trace[2141702284] 'applied index is now lower than readState.Index' (duration: 208.257µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:12:40.076631Z","caller":"traceutil/trace.go:171","msg":"trace[1954140359] transaction","detail":"{read_only:false; response_revision:2399; number_of_response:1; }","duration":"272.630416ms","start":"2026-04-07T00:12:39.803976Z","end":"2026-04-07T00:12:40.076606Z","steps":["trace[1954140359] 'process raft request' (duration: 272.344447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:40.076861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.965495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:6 size:363682"} {"level":"warn","ts":"2026-04-07T00:12:40.076849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.274864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T00:12:40.076901Z","caller":"traceutil/trace.go:171","msg":"trace[1936349316] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2399; }","duration":"272.022437ms","start":"2026-04-07T00:12:39.804865Z","end":"2026-04-07T00:12:40.076888Z","steps":["trace[1936349316] 'agreement among raft nodes before linearized reading' (duration: 271.748298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:40.076896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.221367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-m9b1nx\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-04-07T00:12:40.076941Z","caller":"traceutil/trace.go:171","msg":"trace[1793498642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2399; }","duration":"186.416979ms","start":"2026-04-07T00:12:39.890501Z","end":"2026-04-07T00:12:40.076918Z","steps":["trace[1793498642] 'agreement among raft nodes before linearized reading' (duration: 186.193812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:40.076805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.01091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-07T00:12:40.076952Z","caller":"traceutil/trace.go:171","msg":"trace[734921217] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-m9b1nx; range_end:; response_count:1; response_revision:2399; }","duration":"201.270248ms","start":"2026-04-07T00:12:39.875665Z","end":"2026-04-07T00:12:40.076935Z","steps":["trace[734921217] 'agreement among raft nodes before linearized reading' (duration: 201.184136ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:40.077032Z","caller":"traceutil/trace.go:171","msg":"trace[2121906767] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2399; }","duration":"262.304139ms","start":"2026-04-07T00:12:39.814717Z","end":"2026-04-07T00:12:40.077021Z","steps":["trace[2121906767] 'agreement among raft nodes before linearized reading' (duration: 261.949958ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:40.709915Z","caller":"traceutil/trace.go:171","msg":"trace[2087879579] linearizableReadLoop","detail":"{readStateIndex:2511; appliedIndex:2510; }","duration":"212.515684ms","start":"2026-04-07T00:12:40.497358Z","end":"2026-04-07T00:12:40.709873Z","steps":["trace[2087879579] 'read index received' (duration: 205.339147ms)","trace[2087879579] 'applied index is now lower than readState.Index' (duration: 7.175207ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:12:40.710183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.803953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-04-07T00:12:40.710295Z","caller":"traceutil/trace.go:171","msg":"trace[564217360] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:2404; }","duration":"212.956588ms","start":"2026-04-07T00:12:40.497317Z","end":"2026-04-07T00:12:40.710273Z","steps":["trace[564217360] 'agreement among raft nodes before linearized reading' (duration: 212.691849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:40.710524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.403327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:754"} {"level":"info","ts":"2026-04-07T00:12:40.710577Z","caller":"traceutil/trace.go:171","msg":"trace[1043156008] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:2404; }","duration":"210.48683ms","start":"2026-04-07T00:12:40.500076Z","end":"2026-04-07T00:12:40.710563Z","steps":["trace[1043156008] 'agreement among raft nodes before linearized reading' (duration: 209.985904ms)"],"step_count":1}