{"level":"warn","ts":"2026-03-25T16:49:06.455321Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T16:49:06.455437Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.151:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.151:2380","--initial-cluster=instance=https://199.19.213.151:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.151:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.151: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-03-25T16:49:06.455524Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T16:49:06.45554Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.151:2380"]} {"level":"info","ts":"2026-03-25T16:49:06.45556Z","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-03-25T16:49:06.456478Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.151:2379"]} {"level":"info","ts":"2026-03-25T16:49:06.456567Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.151:2380"],"listen-peer-urls":["https://199.19.213.151:2380"],"advertise-client-urls":["https://199.19.213.151:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.151:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.151: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-03-25T16:49:06.460847Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.014568ms"} {"level":"info","ts":"2026-03-25T16:49:06.469896Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9abf795dae4ebc2c","cluster-id":"8a186cf11cef5bce"} {"level":"info","ts":"2026-03-25T16:49:06.470022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c switched to configuration voters=()"} {"level":"info","ts":"2026-03-25T16:49:06.470053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became follower at term 0"} {"level":"info","ts":"2026-03-25T16:49:06.470074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9abf795dae4ebc2c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-25T16:49:06.470087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became follower at term 1"} {"level":"info","ts":"2026-03-25T16:49:06.470148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c switched to configuration voters=(11150764645655952428)"} {"level":"warn","ts":"2026-03-25T16:49:06.4802Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-25T16:49:06.482361Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-25T16:49:06.483731Z","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-03-25T16:49:06.486302Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9abf795dae4ebc2c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-25T16:49:06.48668Z","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-03-25T16:49:06.486511Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9abf795dae4ebc2c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-25T16:49:06.48685Z","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-03-25T16:49:06.486867Z","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-03-25T16:49:06.489886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c switched to configuration voters=(11150764645655952428)"} {"level":"info","ts":"2026-03-25T16:49:06.490016Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8a186cf11cef5bce","local-member-id":"9abf795dae4ebc2c","added-peer-id":"9abf795dae4ebc2c","added-peer-peer-urls":["https://199.19.213.151:2380"]} {"level":"info","ts":"2026-03-25T16:49:06.491972Z","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-03-25T16:49:06.492011Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.151:2380"} {"level":"info","ts":"2026-03-25T16:49:06.492045Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.151:2380"} {"level":"info","ts":"2026-03-25T16:49:06.49224Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9abf795dae4ebc2c","initial-advertise-peer-urls":["https://199.19.213.151:2380"],"listen-peer-urls":["https://199.19.213.151:2380"],"advertise-client-urls":["https://199.19.213.151:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.151:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-25T16:49:06.49229Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-25T16:49:07.371095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c is starting a new election at term 1"} {"level":"info","ts":"2026-03-25T16:49:07.371167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became pre-candidate at term 1"} {"level":"info","ts":"2026-03-25T16:49:07.371213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c received MsgPreVoteResp from 9abf795dae4ebc2c at term 1"} {"level":"info","ts":"2026-03-25T16:49:07.371237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became candidate at term 2"} {"level":"info","ts":"2026-03-25T16:49:07.371248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c received MsgVoteResp from 9abf795dae4ebc2c at term 2"} {"level":"info","ts":"2026-03-25T16:49:07.371266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became leader at term 2"} {"level":"info","ts":"2026-03-25T16:49:07.371284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9abf795dae4ebc2c elected leader 9abf795dae4ebc2c at term 2"} {"level":"info","ts":"2026-03-25T16:49:07.373479Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9abf795dae4ebc2c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.151:2379]}","request-path":"/0/members/9abf795dae4ebc2c/attributes","cluster-id":"8a186cf11cef5bce","publish-timeout":"7s"} {"level":"info","ts":"2026-03-25T16:49:07.373505Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T16:49:07.373539Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T16:49:07.373676Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T16:49:07.373823Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-25T16:49:07.373924Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-25T16:49:07.374971Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8a186cf11cef5bce","local-member-id":"9abf795dae4ebc2c","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T16:49:07.375207Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T16:49:07.375274Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T16:49:07.376819Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.151:2379"} {"level":"info","ts":"2026-03-25T16:49:07.377182Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-25T16:49:08.748063Z","caller":"traceutil/trace.go:171","msg":"trace[2114231372] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"177.75041ms","start":"2026-03-25T16:49:08.570285Z","end":"2026-03-25T16:49:08.748035Z","steps":["trace[2114231372] 'process raft request' (duration: 177.537005ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:09.203009Z","caller":"traceutil/trace.go:171","msg":"trace[1442191698] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"632.451169ms","start":"2026-03-25T16:49:08.570497Z","end":"2026-03-25T16:49:09.202949Z","steps":["trace[1442191698] 'process raft request' (duration: 567.153623ms)","trace[1442191698] 'compare' (duration: 65.00959ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:49:09.20306Z","caller":"traceutil/trace.go:171","msg":"trace[211426955] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"632.145923ms","start":"2026-03-25T16:49:08.57089Z","end":"2026-03-25T16:49:09.203036Z","steps":["trace[211426955] 'read index received' (duration: 176.926562ms)","trace[211426955] 'applied index is now lower than readState.Index' (duration: 455.217941ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:49:09.203241Z","caller":"traceutil/trace.go:171","msg":"trace[1539911947] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"562.378251ms","start":"2026-03-25T16:49:08.640844Z","end":"2026-03-25T16:49:09.203222Z","steps":["trace[1539911947] 'process raft request' (duration: 561.958842ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:09.203053Z","caller":"traceutil/trace.go:171","msg":"trace[1014344742] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"561.39169ms","start":"2026-03-25T16:49:08.641604Z","end":"2026-03-25T16:49:09.202996Z","steps":["trace[1014344742] 'process raft request' (duration: 561.233846ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:09.203226Z","caller":"traceutil/trace.go:171","msg":"trace[323137573] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"560.85601ms","start":"2026-03-25T16:49:08.642243Z","end":"2026-03-25T16:49:09.203099Z","steps":["trace[323137573] 'process raft request' (duration: 560.651685ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:09.203434Z","caller":"traceutil/trace.go:171","msg":"trace[356886868] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"541.652829ms","start":"2026-03-25T16:49:08.661712Z","end":"2026-03-25T16:49:09.203365Z","steps":["trace[356886868] 'process raft request' (duration: 541.295151ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:09.203464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.62979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T16:49:09.203526Z","caller":"traceutil/trace.go:171","msg":"trace[1293679868] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:11; }","duration":"630.778025ms","start":"2026-03-25T16:49:08.572737Z","end":"2026-03-25T16:49:09.203515Z","steps":["trace[1293679868] 'agreement among raft nodes before linearized reading' (duration: 630.599651ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:09.203588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.685705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T16:49:09.203594Z","caller":"traceutil/trace.go:171","msg":"trace[1431993657] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"561.238388ms","start":"2026-03-25T16:49:08.642337Z","end":"2026-03-25T16:49:09.203576Z","steps":["trace[1431993657] 'process raft request' (duration: 560.577753ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:09.203561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:08.570821Z","time spent":"632.732475ms","remote":"127.0.0.1:57144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-03-25T16:49:09.203669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.508974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T16:49:09.203699Z","caller":"traceutil/trace.go:171","msg":"trace[667217361] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"576.567355ms","start":"2026-03-25T16:49:08.627124Z","end":"2026-03-25T16:49:09.203691Z","steps":["trace[667217361] 'agreement among raft nodes before linearized reading' (duration: 576.528034ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:09.203697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.360401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-25T16:49:09.203725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:08.62711Z","time spent":"576.608826ms","remote":"127.0.0.1:57016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-25T16:49:09.203631Z","caller":"traceutil/trace.go:171","msg":"trace[359635991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"632.771127ms","start":"2026-03-25T16:49:08.570848Z","end":"2026-03-25T16:49:09.203619Z","steps":["trace[359635991] 'agreement among raft nodes before linearized reading' (duration: 632.672625ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:09.203763Z","caller":"traceutil/trace.go:171","msg":"trace[1985442128] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"562.466014ms","start":"2026-03-25T16:49:08.641264Z","end":"2026-03-25T16:49:09.20373Z","steps":["trace[1985442128] 'agreement among raft nodes before linearized reading' (duration: 562.327511ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:09.203797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:08.570842Z","time spent":"632.94449ms","remote":"127.0.0.1:57144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-25T16:49:09.203795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:08.641247Z","time spent":"562.539885ms","remote":"127.0.0.1:57016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-25T16:49:09.20337Z","caller":"traceutil/trace.go:171","msg":"trace[1798210483] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"550.848366ms","start":"2026-03-25T16:49:08.65249Z","end":"2026-03-25T16:49:09.203338Z","steps":["trace[1798210483] 'process raft request' (duration: 550.479167ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:09.203887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.508874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T16:49:09.203912Z","caller":"traceutil/trace.go:171","msg":"trace[879061919] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"511.531475ms","start":"2026-03-25T16:49:08.692373Z","end":"2026-03-25T16:49:09.203905Z","steps":["trace[879061919] 'agreement among raft nodes before linearized reading' (duration: 511.497344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:09.20393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:08.692338Z","time spent":"511.587706ms","remote":"127.0.0.1:57016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-25T16:49:09.203972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.411735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"warn","ts":"2026-03-25T16:49:09.20402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.116744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T16:49:09.203936Z","caller":"traceutil/trace.go:171","msg":"trace[527315620] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"561.694827ms","start":"2026-03-25T16:49:08.642224Z","end":"2026-03-25T16:49:09.203919Z","steps":["trace[527315620] 'process raft request' (duration: 560.644544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:09.204055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.183254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T16:49:09.204091Z","caller":"traceutil/trace.go:171","msg":"trace[39442028] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"588.219594ms","start":"2026-03-25T16:49:08.615864Z","end":"2026-03-25T16:49:09.204083Z","steps":["trace[39442028] 'agreement among raft nodes before linearized reading' (duration: 588.171053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:09.204165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:08.615828Z","time spent":"588.328926ms","remote":"127.0.0.1:57016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-25T16:49:09.204018Z","caller":"traceutil/trace.go:171","msg":"trace[1223757626] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"629.465846ms","start":"2026-03-25T16:49:08.574539Z","end":"2026-03-25T16:49:09.204005Z","steps":["trace[1223757626] 'agreement among raft nodes before linearized reading' (duration: 629.383374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:09.204242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:08.574533Z","time spent":"629.698771ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3195,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-25T16:49:09.204042Z","caller":"traceutil/trace.go:171","msg":"trace[956298169] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"561.163285ms","start":"2026-03-25T16:49:08.642871Z","end":"2026-03-25T16:49:09.204034Z","steps":["trace[956298169] 'agreement among raft nodes before linearized reading' (duration: 561.104794ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:09.204403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:08.642862Z","time spent":"561.464442ms","remote":"127.0.0.1:56994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-03-25T16:49:09.203636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.072056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.151\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T16:49:09.204663Z","caller":"traceutil/trace.go:171","msg":"trace[2120505279] range","detail":"{range_begin:/registry/masterleases/199.19.213.151; range_end:; response_count:0; response_revision:11; }","duration":"564.117878ms","start":"2026-03-25T16:49:08.640534Z","end":"2026-03-25T16:49:09.204651Z","steps":["trace[2120505279] 'agreement among raft nodes before linearized reading' (duration: 563.084397ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:09.204709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:08.64052Z","time spent":"564.178349ms","remote":"127.0.0.1:56912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.19.213.151\" "} {"level":"info","ts":"2026-03-25T16:49:09.203064Z","caller":"traceutil/trace.go:171","msg":"trace[751054665] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"560.669105ms","start":"2026-03-25T16:49:08.642382Z","end":"2026-03-25T16:49:09.203051Z","steps":["trace[751054665] 'process raft request' (duration: 560.559953ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:09.20742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:08.642373Z","time spent":"562.523384ms","remote":"127.0.0.1:57390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T16:49:09.207628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:08.640823Z","time spent":"562.463834ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T16:49:09.207772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:08.641583Z","time spent":"561.743689ms","remote":"127.0.0.1:57390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T16:49:09.208053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:08.661705Z","time spent":"541.809623ms","remote":"127.0.0.1:57142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T16:49:09.208536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:08.570485Z","time spent":"632.622413ms","remote":"127.0.0.1:56956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T16:49:09.208253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:08.642329Z","time spent":"561.33107ms","remote":"127.0.0.1:57390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T16:49:09.208933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:08.65248Z","time spent":"551.387097ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T16:49:09.208837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:08.642215Z","time spent":"561.277689ms","remote":"127.0.0.1:57390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T16:49:09.209032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:08.642212Z","time spent":"561.872721ms","remote":"127.0.0.1:57390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T16:49:09.448323Z","caller":"traceutil/trace.go:171","msg":"trace[1554421490] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:14; }","duration":"240.767581ms","start":"2026-03-25T16:49:09.207532Z","end":"2026-03-25T16:49:09.448299Z","steps":["trace[1554421490] 'read index received' (duration: 218.209302ms)","trace[1554421490] 'applied index is now lower than readState.Index' (duration: 22.556309ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:49:09.448381Z","caller":"traceutil/trace.go:171","msg":"trace[397195200] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"241.25305ms","start":"2026-03-25T16:49:09.207111Z","end":"2026-03-25T16:49:09.448364Z","steps":["trace[397195200] 'process raft request' (duration: 218.511367ms)","trace[397195200] 'compare' (duration: 22.556179ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:49:09.44866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.111708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T16:49:09.448705Z","caller":"traceutil/trace.go:171","msg":"trace[234478199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12; }","duration":"241.740201ms","start":"2026-03-25T16:49:09.206954Z","end":"2026-03-25T16:49:09.448694Z","steps":["trace[234478199] 'agreement among raft nodes before linearized reading' (duration: 241.463955ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:09.448969Z","caller":"traceutil/trace.go:171","msg":"trace[734850433] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"240.373662ms","start":"2026-03-25T16:49:09.20858Z","end":"2026-03-25T16:49:09.448954Z","steps":["trace[734850433] 'process raft request' (duration: 239.994734ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:09.449234Z","caller":"traceutil/trace.go:171","msg":"trace[2122304822] transaction","detail":"{read_only:false; number_of_response:0; response_revision:13; }","duration":"240.511434ms","start":"2026-03-25T16:49:09.208711Z","end":"2026-03-25T16:49:09.449223Z","steps":["trace[2122304822] 'process raft request' (duration: 240.165267ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:09.449466Z","caller":"traceutil/trace.go:171","msg":"trace[1279543924] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"237.840477ms","start":"2026-03-25T16:49:09.211591Z","end":"2026-03-25T16:49:09.449432Z","steps":["trace[1279543924] 'process raft request' (duration: 237.351227ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:09.449543Z","caller":"traceutil/trace.go:171","msg":"trace[1301607894] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"237.900948ms","start":"2026-03-25T16:49:09.211625Z","end":"2026-03-25T16:49:09.449526Z","steps":["trace[1301607894] 'process raft request' (duration: 237.557461ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:09.449788Z","caller":"traceutil/trace.go:171","msg":"trace[1315575093] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"238.139834ms","start":"2026-03-25T16:49:09.211614Z","end":"2026-03-25T16:49:09.449754Z","steps":["trace[1315575093] 'process raft request' (duration: 237.421508ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:09.449863Z","caller":"traceutil/trace.go:171","msg":"trace[844655664] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"237.976751ms","start":"2026-03-25T16:49:09.211862Z","end":"2026-03-25T16:49:09.449839Z","steps":["trace[844655664] 'process raft request' (duration: 237.370387ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:09.450211Z","caller":"traceutil/trace.go:171","msg":"trace[2113774507] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"238.266317ms","start":"2026-03-25T16:49:09.211913Z","end":"2026-03-25T16:49:09.45018Z","steps":["trace[2113774507] 'process raft request' (duration: 237.363437ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:09.450537Z","caller":"traceutil/trace.go:171","msg":"trace[1318931065] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"237.560262ms","start":"2026-03-25T16:49:09.212966Z","end":"2026-03-25T16:49:09.450526Z","steps":["trace[1318931065] 'process raft request' (duration: 236.357356ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:09.450848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.330284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-03-25T16:49:09.450854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.303571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-03-25T16:49:09.450891Z","caller":"traceutil/trace.go:171","msg":"trace[536932710] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"185.401824ms","start":"2026-03-25T16:49:09.265479Z","end":"2026-03-25T16:49:09.450881Z","steps":["trace[536932710] 'agreement among raft nodes before linearized reading' (duration: 185.312413ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:09.451178Z","caller":"traceutil/trace.go:171","msg":"trace[1891678337] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"233.775661ms","start":"2026-03-25T16:49:09.217385Z","end":"2026-03-25T16:49:09.45116Z","steps":["trace[1891678337] 'process raft request' (duration: 233.111717ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:09.451498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.118703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T16:49:09.451546Z","caller":"traceutil/trace.go:171","msg":"trace[758620354] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"213.173624ms","start":"2026-03-25T16:49:09.238359Z","end":"2026-03-25T16:49:09.451533Z","steps":["trace[758620354] 'agreement among raft nodes before linearized reading' (duration: 213.098242ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:09.450908Z","caller":"traceutil/trace.go:171","msg":"trace[115857027] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20; }","duration":"241.420814ms","start":"2026-03-25T16:49:09.209473Z","end":"2026-03-25T16:49:09.450894Z","steps":["trace[115857027] 'agreement among raft nodes before linearized reading' (duration: 241.25899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:09.451618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.188868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-ztjtk\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-03-25T16:49:09.451653Z","caller":"traceutil/trace.go:171","msg":"trace[648133706] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-ztjtk; range_end:; response_count:1; response_revision:20; }","duration":"240.256599ms","start":"2026-03-25T16:49:09.211382Z","end":"2026-03-25T16:49:09.451639Z","steps":["trace[648133706] 'agreement among raft nodes before linearized reading' (duration: 240.197188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:09.452248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.703829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-25T16:49:09.452334Z","caller":"traceutil/trace.go:171","msg":"trace[1154877728] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"191.8262ms","start":"2026-03-25T16:49:09.260495Z","end":"2026-03-25T16:49:09.452322Z","steps":["trace[1154877728] 'agreement among raft nodes before linearized reading' (duration: 190.431871ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:38.445388Z","caller":"traceutil/trace.go:171","msg":"trace[847183255] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"118.972005ms","start":"2026-03-25T16:49:38.326398Z","end":"2026-03-25T16:49:38.44537Z","steps":["trace[847183255] 'process raft request' (duration: 118.878494ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:38.452652Z","caller":"traceutil/trace.go:171","msg":"trace[1766610584] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"118.590719ms","start":"2026-03-25T16:49:38.333993Z","end":"2026-03-25T16:49:38.452583Z","steps":["trace[1766610584] 'process raft request' (duration: 118.519038ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:38.45264Z","caller":"traceutil/trace.go:171","msg":"trace[1734654684] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"125.949179ms","start":"2026-03-25T16:49:38.326619Z","end":"2026-03-25T16:49:38.452568Z","steps":["trace[1734654684] 'process raft request' (duration: 125.807527ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:44.66724Z","caller":"traceutil/trace.go:171","msg":"trace[1749591267] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"104.301198ms","start":"2026-03-25T16:49:44.562902Z","end":"2026-03-25T16:49:44.667204Z","steps":["trace[1749591267] 'process raft request' (duration: 87.025082ms)","trace[1749591267] 'compare' (duration: 16.982132ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:49:45.873291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.810732ms","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-03-25T16:49:45.873399Z","caller":"traceutil/trace.go:171","msg":"trace[1119440096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"900.960244ms","start":"2026-03-25T16:49:44.972414Z","end":"2026-03-25T16:49:45.873374Z","steps":["trace[1119440096] 'range keys from in-memory index tree' (duration: 900.65465ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:45.873452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:44.972396Z","time spent":"901.043876ms","remote":"127.0.0.1:57144","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-03-25T16:49:45.873678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.600589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9hfrj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-25T16:49:45.873789Z","caller":"traceutil/trace.go:171","msg":"trace[1476737933] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9hfrj; range_end:; response_count:1; response_revision:623; }","duration":"824.745392ms","start":"2026-03-25T16:49:45.049016Z","end":"2026-03-25T16:49:45.873762Z","steps":["trace[1476737933] 'range keys from in-memory index tree' (duration: 824.489727ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:45.873848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:45.048998Z","time spent":"824.832443ms","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-9hfrj\" "} {"level":"info","ts":"2026-03-25T16:49:45.873899Z","caller":"traceutil/trace.go:171","msg":"trace[1333072981] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"706.631615ms","start":"2026-03-25T16:49:45.167246Z","end":"2026-03-25T16:49:45.873878Z","steps":["trace[1333072981] 'process raft request' (duration: 682.748507ms)","trace[1333072981] 'compare' (duration: 23.465551ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:49:45.874663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:45.167222Z","time spent":"706.720287ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T16:49:45.95679Z","caller":"traceutil/trace.go:171","msg":"trace[1856631612] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:646; }","duration":"307.77134ms","start":"2026-03-25T16:49:45.64898Z","end":"2026-03-25T16:49:45.956751Z","steps":["trace[1856631612] 'read index received' (duration: 200.950268ms)","trace[1856631612] 'applied index is now lower than readState.Index' (duration: 106.814582ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:49:45.957168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.148417ms","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-03-25T16:49:45.957297Z","caller":"traceutil/trace.go:171","msg":"trace[1256259989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"308.33028ms","start":"2026-03-25T16:49:45.648943Z","end":"2026-03-25T16:49:45.957273Z","steps":["trace[1256259989] 'agreement among raft nodes before linearized reading' (duration: 307.943322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:45.957359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:45.648902Z","time spent":"308.447432ms","remote":"127.0.0.1:57144","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-03-25T16:49:46.015782Z","caller":"traceutil/trace.go:171","msg":"trace[1888777658] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"126.559327ms","start":"2026-03-25T16:49:45.889194Z","end":"2026-03-25T16:49:46.015753Z","steps":["trace[1888777658] 'process raft request' (duration: 85.59884ms)","trace[1888777658] 'compare' (duration: 40.815065ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:49:46.64963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.762558ms","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-03-25T16:49:46.649788Z","caller":"traceutil/trace.go:171","msg":"trace[1776220951] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:626; }","duration":"524.945191ms","start":"2026-03-25T16:49:46.124829Z","end":"2026-03-25T16:49:46.649774Z","steps":["trace[1776220951] 'range keys from in-memory index tree' (duration: 524.750218ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:46.650129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.673645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T16:49:46.650391Z","caller":"traceutil/trace.go:171","msg":"trace[859365100] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"688.559723ms","start":"2026-03-25T16:49:45.961822Z","end":"2026-03-25T16:49:46.650381Z","steps":["trace[859365100] 'process raft request' (duration: 688.39538ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:46.650513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:45.961795Z","time spent":"688.653765ms","remote":"127.0.0.1:57144","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-03-25T16:49:46.650605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.997497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-25T16:49:46.650386Z","caller":"traceutil/trace.go:171","msg":"trace[1748010796] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:650; }","duration":"552.766573ms","start":"2026-03-25T16:49:46.097599Z","end":"2026-03-25T16:49:46.650366Z","steps":["trace[1748010796] 'read index received' (duration: 79.449838ms)","trace[1748010796] 'applied index is now lower than readState.Index' (duration: 473.315775ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:49:46.650694Z","caller":"traceutil/trace.go:171","msg":"trace[1949777873] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"553.124769ms","start":"2026-03-25T16:49:46.097554Z","end":"2026-03-25T16:49:46.650679Z","steps":["trace[1949777873] 'agreement among raft nodes before linearized reading' (duration: 552.875745ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:46.650806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:46.097535Z","time spent":"553.255691ms","remote":"127.0.0.1:57144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-25T16:49:46.650852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.967746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:49:46.650915Z","caller":"traceutil/trace.go:171","msg":"trace[1740874919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"292.062528ms","start":"2026-03-25T16:49:46.358838Z","end":"2026-03-25T16:49:46.6509Z","steps":["trace[1740874919] 'agreement among raft nodes before linearized reading' (duration: 291.948596ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:46.651296Z","caller":"traceutil/trace.go:171","msg":"trace[1897869209] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"707.305602ms","start":"2026-03-25T16:49:45.943056Z","end":"2026-03-25T16:49:46.650361Z","steps":["trace[1897869209] 'process raft request' (duration: 234.075778ms)","trace[1897869209] 'compare' (duration: 472.385ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:49:46.651347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.567946ms","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":"warn","ts":"2026-03-25T16:49:46.651481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.702767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:49:46.651484Z","caller":"traceutil/trace.go:171","msg":"trace[1912081889] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:628; }","duration":"110.723468ms","start":"2026-03-25T16:49:46.540741Z","end":"2026-03-25T16:49:46.651464Z","steps":["trace[1912081889] 'agreement among raft nodes before linearized reading' (duration: 110.562576ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:46.65151Z","caller":"traceutil/trace.go:171","msg":"trace[843107092] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:628; }","duration":"134.752057ms","start":"2026-03-25T16:49:46.51675Z","end":"2026-03-25T16:49:46.651502Z","steps":["trace[843107092] 'agreement among raft nodes before linearized reading' (duration: 134.669486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:46.651681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.029527ms","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-03-25T16:49:46.651725Z","caller":"traceutil/trace.go:171","msg":"trace[1415894789] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:628; }","duration":"163.071859ms","start":"2026-03-25T16:49:46.488628Z","end":"2026-03-25T16:49:46.6517Z","steps":["trace[1415894789] 'agreement among raft nodes before linearized reading' (duration: 163.031788ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:46.65191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.992891ms","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-03-25T16:49:46.651937Z","caller":"traceutil/trace.go:171","msg":"trace[132863169] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:628; }","duration":"165.049962ms","start":"2026-03-25T16:49:46.48688Z","end":"2026-03-25T16:49:46.65193Z","steps":["trace[132863169] 'agreement among raft nodes before linearized reading' (duration: 164.954741ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:46.652168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.687019ms","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":"info","ts":"2026-03-25T16:49:46.652227Z","caller":"traceutil/trace.go:171","msg":"trace[1146037933] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:628; }","duration":"110.758531ms","start":"2026-03-25T16:49:46.541454Z","end":"2026-03-25T16:49:46.652213Z","steps":["trace[1146037933] 'agreement among raft nodes before linearized reading' (duration: 110.67709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:46.652516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.593135ms","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":"info","ts":"2026-03-25T16:49:46.652569Z","caller":"traceutil/trace.go:171","msg":"trace[1388347303] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:628; }","duration":"111.693756ms","start":"2026-03-25T16:49:46.540863Z","end":"2026-03-25T16:49:46.652557Z","steps":["trace[1388347303] 'agreement among raft nodes before linearized reading' (duration: 111.608244ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:46.652222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.378478ms","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-03-25T16:49:46.652745Z","caller":"traceutil/trace.go:171","msg":"trace[768198907] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:628; }","duration":"165.919857ms","start":"2026-03-25T16:49:46.486811Z","end":"2026-03-25T16:49:46.652731Z","steps":["trace[768198907] 'agreement among raft nodes before linearized reading' (duration: 165.386658ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:46.651368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:49:45.943031Z","time spent":"708.305368ms","remote":"127.0.0.1:57138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T16:49:46.651407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.609095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:49:46.653049Z","caller":"traceutil/trace.go:171","msg":"trace[488073828] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:628; }","duration":"136.246754ms","start":"2026-03-25T16:49:46.516785Z","end":"2026-03-25T16:49:46.653032Z","steps":["trace[488073828] 'agreement among raft nodes before linearized reading' (duration: 134.606405ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:46.651371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.524733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:49:46.653245Z","caller":"traceutil/trace.go:171","msg":"trace[314515159] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:628; }","duration":"136.411056ms","start":"2026-03-25T16:49:46.516812Z","end":"2026-03-25T16:49:46.653223Z","steps":["trace[314515159] 'agreement among raft nodes before linearized reading' (duration: 134.514683ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:46.917826Z","caller":"traceutil/trace.go:171","msg":"trace[85331677] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"260.94376ms","start":"2026-03-25T16:49:46.656861Z","end":"2026-03-25T16:49:46.917805Z","steps":["trace[85331677] 'read index received' (duration: 230.641246ms)","trace[85331677] 'applied index is now lower than readState.Index' (duration: 30.301534ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:49:46.918004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.104483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:49:46.918053Z","caller":"traceutil/trace.go:171","msg":"trace[760913375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"261.209735ms","start":"2026-03-25T16:49:46.656831Z","end":"2026-03-25T16:49:46.918041Z","steps":["trace[760913375] 'agreement among raft nodes before linearized reading' (duration: 261.047132ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:46.918088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.945373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-03-25T16:49:46.918108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.391972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-25T16:49:46.918164Z","caller":"traceutil/trace.go:171","msg":"trace[1549983951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"147.490124ms","start":"2026-03-25T16:49:46.770662Z","end":"2026-03-25T16:49:46.918152Z","steps":["trace[1549983951] 'agreement among raft nodes before linearized reading' (duration: 147.313051ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:46.918196Z","caller":"traceutil/trace.go:171","msg":"trace[1528045585] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:629; }","duration":"260.052005ms","start":"2026-03-25T16:49:46.658076Z","end":"2026-03-25T16:49:46.918128Z","steps":["trace[1528045585] 'agreement among raft nodes before linearized reading' (duration: 259.892082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:46.918287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.082174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T16:49:46.918333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.183926ms","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":"info","ts":"2026-03-25T16:49:46.91835Z","caller":"traceutil/trace.go:171","msg":"trace[811205212] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:629; }","duration":"151.232008ms","start":"2026-03-25T16:49:46.767112Z","end":"2026-03-25T16:49:46.918344Z","steps":["trace[811205212] 'agreement among raft nodes before linearized reading' (duration: 151.210047ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:46.91834Z","caller":"traceutil/trace.go:171","msg":"trace[1446354563] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:629; }","duration":"151.141005ms","start":"2026-03-25T16:49:46.767186Z","end":"2026-03-25T16:49:46.918327Z","steps":["trace[1446354563] 'agreement among raft nodes before linearized reading' (duration: 151.054794ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:46.918305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.546016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:49:46.918524Z","caller":"traceutil/trace.go:171","msg":"trace[562929500] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:629; }","duration":"150.809091ms","start":"2026-03-25T16:49:46.767705Z","end":"2026-03-25T16:49:46.918514Z","steps":["trace[562929500] 'agreement among raft nodes before linearized reading' (duration: 150.583277ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:46.918534Z","caller":"traceutil/trace.go:171","msg":"trace[776230025] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"261.900847ms","start":"2026-03-25T16:49:46.656617Z","end":"2026-03-25T16:49:46.918518Z","steps":["trace[776230025] 'process raft request' (duration: 230.9013ms)","trace[776230025] 'compare' (duration: 30.187212ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:49:48.352784Z","caller":"traceutil/trace.go:171","msg":"trace[555181878] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"167.541776ms","start":"2026-03-25T16:49:48.185222Z","end":"2026-03-25T16:49:48.352764Z","steps":["trace[555181878] 'read index received' (duration: 113.950575ms)","trace[555181878] 'applied index is now lower than readState.Index' (duration: 53.590651ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:49:48.352894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.657408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:49:48.352915Z","caller":"traceutil/trace.go:171","msg":"trace[591908650] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:650; }","duration":"168.215767ms","start":"2026-03-25T16:49:48.184691Z","end":"2026-03-25T16:49:48.352907Z","steps":["trace[591908650] 'agreement among raft nodes before linearized reading' (duration: 168.163936ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:49:48.353059Z","caller":"traceutil/trace.go:171","msg":"trace[2101447101] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"168.087455ms","start":"2026-03-25T16:49:48.184797Z","end":"2026-03-25T16:49:48.352884Z","steps":["trace[2101447101] 'process raft request' (duration: 114.267601ms)","trace[2101447101] 'compare' (duration: 53.59719ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:49:48.35851Z","caller":"traceutil/trace.go:171","msg":"trace[1645214728] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"170.320143ms","start":"2026-03-25T16:49:48.188182Z","end":"2026-03-25T16:49:48.358502Z","steps":["trace[1645214728] 'process raft request' (duration: 170.282763ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:49:48.358757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.954541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:49:48.358892Z","caller":"traceutil/trace.go:171","msg":"trace[317941358] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:651; }","duration":"172.111603ms","start":"2026-03-25T16:49:48.186749Z","end":"2026-03-25T16:49:48.358861Z","steps":["trace[317941358] 'agreement among raft nodes before linearized reading' (duration: 171.764808ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:50:01.79476Z","caller":"traceutil/trace.go:171","msg":"trace[447606213] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"120.609701ms","start":"2026-03-25T16:50:01.674129Z","end":"2026-03-25T16:50:01.794739Z","steps":["trace[447606213] 'process raft request' (duration: 52.99979ms)","trace[447606213] 'compare' (duration: 67.492719ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:50:02.361651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.985423ms","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-03-25T16:50:02.361718Z","caller":"traceutil/trace.go:171","msg":"trace[700924372] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"333.112786ms","start":"2026-03-25T16:50:02.028593Z","end":"2026-03-25T16:50:02.361706Z","steps":["trace[700924372] 'range keys from in-memory index tree' (duration: 332.79889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:50:02.361745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:50:02.028574Z","time spent":"333.165917ms","remote":"127.0.0.1:57144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-25T16:50:02.483822Z","caller":"traceutil/trace.go:171","msg":"trace[166850122] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"127.280117ms","start":"2026-03-25T16:50:02.356509Z","end":"2026-03-25T16:50:02.483789Z","steps":["trace[166850122] 'read index received' (duration: 126.548416ms)","trace[166850122] 'applied index is now lower than readState.Index' (duration: 730.061µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:50:02.483884Z","caller":"traceutil/trace.go:171","msg":"trace[981793440] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"332.786781ms","start":"2026-03-25T16:50:02.15106Z","end":"2026-03-25T16:50:02.483847Z","steps":["trace[981793440] 'process raft request' (duration: 331.971089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:50:02.484048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.526292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:50:02.484168Z","caller":"traceutil/trace.go:171","msg":"trace[1519725721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:785; }","duration":"127.691284ms","start":"2026-03-25T16:50:02.356449Z","end":"2026-03-25T16:50:02.48414Z","steps":["trace[1519725721] 'agreement among raft nodes before linearized reading' (duration: 127.504011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:50:02.484184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:50:02.15104Z","time spent":"332.925993ms","remote":"127.0.0.1:57138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T16:50:02.486955Z","caller":"traceutil/trace.go:171","msg":"trace[636869609] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"121.183362ms","start":"2026-03-25T16:50:02.365752Z","end":"2026-03-25T16:50:02.486935Z","steps":["trace[636869609] 'process raft request' (duration: 121.000209ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:52:23.913522Z","caller":"traceutil/trace.go:171","msg":"trace[1629036580] linearizableReadLoop","detail":"{readStateIndex:1495; appliedIndex:1494; }","duration":"106.797231ms","start":"2026-03-25T16:52:23.806704Z","end":"2026-03-25T16:52:23.913501Z","steps":["trace[1629036580] 'read index received' (duration: 106.687108ms)","trace[1629036580] 'applied index is now lower than readState.Index' (duration: 109.213µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:52:23.913581Z","caller":"traceutil/trace.go:171","msg":"trace[856357551] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"107.174041ms","start":"2026-03-25T16:52:23.806378Z","end":"2026-03-25T16:52:23.913553Z","steps":["trace[856357551] 'process raft request' (duration: 106.985096ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:52:23.913672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.949155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-25T16:52:23.913707Z","caller":"traceutil/trace.go:171","msg":"trace[176555421] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:1; response_revision:1435; }","duration":"107.019227ms","start":"2026-03-25T16:52:23.806678Z","end":"2026-03-25T16:52:23.913697Z","steps":["trace[176555421] 'agreement among raft nodes before linearized reading' (duration: 106.940475ms)"],"step_count":1}