{"level":"warn","ts":"2026-04-08T00:33:00.68414Z","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-08T00:33:00.684256Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-08T00:33:00.684358Z","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-08T00:33:00.68438Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-08T00:33:00.684417Z","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-08T00:33:00.685286Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-04-08T00:33:00.685385Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-08T00:33:00.689387Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.752063ms"} {"level":"info","ts":"2026-04-08T00:33:00.696552Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-04-08T00:33:00.696598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T00:33:00.696618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-04-08T00:33:00.696627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T00:33:00.696634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-04-08T00:33:00.696666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-04-08T00:33:00.701237Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T00:33:00.702381Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T00:33:00.703293Z","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-08T00:33:00.705236Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T00:33:00.705452Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T00:33:00.70549Z","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-08T00:33:00.705613Z","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-08T00:33:00.705694Z","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-08T00:33:00.70612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-04-08T00:33:00.706224Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-08T00:33:00.711904Z","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-08T00:33:00.711997Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-08T00:33:00.712084Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-08T00:33:00.712741Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T00:33:00.712772Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T00:33:01.497476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T00:33:01.497532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T00:33:01.497558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-04-08T00:33:01.49757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-04-08T00:33:01.497574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-08T00:33:01.497584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-04-08T00:33:01.497592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-08T00:33:01.643446Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:33:01.646544Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T00:33:01.646597Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:33:01.64656Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:33:01.647113Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:33:01.647859Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T00:33:01.647901Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T00:33:01.647917Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:33:01.647984Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:33:01.649977Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T00:33:01.650082Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"warn","ts":"2026-04-08T00:33:04.040894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.538973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-08T00:33:04.041158Z","caller":"traceutil/trace.go:171","msg":"trace[14765742] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"994.947032ms","start":"2026-04-08T00:33:03.04619Z","end":"2026-04-08T00:33:04.041137Z","steps":["trace[14765742] 'process raft request' (duration: 994.831377ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:04.041234Z","caller":"traceutil/trace.go:171","msg":"trace[801344949] linearizableReadLoop","detail":"{readStateIndex:43; appliedIndex:36; }","duration":"952.053504ms","start":"2026-04-08T00:33:03.089165Z","end":"2026-04-08T00:33:04.041218Z","steps":["trace[801344949] 'read index received' (duration: 244.780957ms)","trace[801344949] 'applied index is now lower than readState.Index' (duration: 707.271567ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:04.041245Z","caller":"traceutil/trace.go:171","msg":"trace[1117319415] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"993.68202ms","start":"2026-04-08T00:33:03.047515Z","end":"2026-04-08T00:33:04.041196Z","steps":["trace[1117319415] 'process raft request' (duration: 993.582837ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:04.041148Z","caller":"traceutil/trace.go:171","msg":"trace[2040290952] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"995.036384ms","start":"2026-04-08T00:33:03.046059Z","end":"2026-04-08T00:33:04.041095Z","steps":["trace[2040290952] 'process raft request' (duration: 287.811829ms)","trace[2040290952] 'compare' (duration: 706.316865ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:33:04.041401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:03.047506Z","time spent":"993.815264ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:33:04.041524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:03.046042Z","time spent":"995.370965ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:33:04.04132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:03.04614Z","time spent":"995.099487ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:33:04.042063Z","caller":"traceutil/trace.go:171","msg":"trace[1055615531] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"995.586111ms","start":"2026-04-08T00:33:03.046436Z","end":"2026-04-08T00:33:04.042022Z","steps":["trace[1055615531] 'process raft request' (duration: 994.628419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:04.042162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:03.046394Z","time spent":"995.718306ms","remote":"127.0.0.1:40996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":326,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:33:04.042195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.979515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T00:33:04.042285Z","caller":"traceutil/trace.go:171","msg":"trace[1979824394] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:39; }","duration":"953.14473ms","start":"2026-04-08T00:33:03.089116Z","end":"2026-04-08T00:33:04.042261Z","steps":["trace[1979824394] 'agreement among raft nodes before linearized reading' (duration: 952.921683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:04.042331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:03.089093Z","time spent":"953.228703ms","remote":"127.0.0.1:41186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-04-08T00:33:04.042341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.797303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:1 size:338"} {"level":"info","ts":"2026-04-08T00:33:04.042378Z","caller":"traceutil/trace.go:171","msg":"trace[1201992983] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:1; response_revision:39; }","duration":"943.856405ms","start":"2026-04-08T00:33:03.098508Z","end":"2026-04-08T00:33:04.042365Z","steps":["trace[1201992983] 'agreement among raft nodes before linearized reading' (duration: 943.784703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:04.042418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:03.098495Z","time spent":"943.917187ms","remote":"127.0.0.1:40996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":1,"response size":360,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-04-08T00:33:04.04274Z","caller":"traceutil/trace.go:171","msg":"trace[576201451] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"994.535348ms","start":"2026-04-08T00:33:03.048187Z","end":"2026-04-08T00:33:04.042722Z","steps":["trace[576201451] 'process raft request' (duration: 992.991658ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:04.042736Z","caller":"traceutil/trace.go:171","msg":"trace[1037709999] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"995.065864ms","start":"2026-04-08T00:33:03.04765Z","end":"2026-04-08T00:33:04.042716Z","steps":["trace[1037709999] 'process raft request' (duration: 993.506523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:04.042849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.641132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T00:33:04.042891Z","caller":"traceutil/trace.go:171","msg":"trace[2028226453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:39; }","duration":"215.696714ms","start":"2026-04-08T00:33:03.827184Z","end":"2026-04-08T00:33:04.04288Z","steps":["trace[2028226453] 'agreement among raft nodes before linearized reading' (duration: 215.638902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:04.042906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:03.047641Z","time spent":"995.20941ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:33:04.043028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.735777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T00:33:04.043076Z","caller":"traceutil/trace.go:171","msg":"trace[1804412483] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:39; }","duration":"223.81553ms","start":"2026-04-08T00:33:03.819245Z","end":"2026-04-08T00:33:04.04306Z","steps":["trace[1804412483] 'agreement among raft nodes before linearized reading' (duration: 223.756278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:04.043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.68838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-08T00:33:04.042831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.46402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T00:33:04.043198Z","caller":"traceutil/trace.go:171","msg":"trace[1492553313] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:39; }","duration":"334.867113ms","start":"2026-04-08T00:33:03.70831Z","end":"2026-04-08T00:33:04.043177Z","steps":["trace[1492553313] 'agreement among raft nodes before linearized reading' (duration: 334.46574ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:04.043207Z","caller":"traceutil/trace.go:171","msg":"trace[1306919726] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:39; }","duration":"221.878646ms","start":"2026-04-08T00:33:03.821278Z","end":"2026-04-08T00:33:04.043157Z","steps":["trace[1306919726] 'agreement among raft nodes before linearized reading' (duration: 221.583047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:04.043261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:03.708269Z","time spent":"334.975197ms","remote":"127.0.0.1:41034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":26,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-04-08T00:33:04.043862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.182717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-08T00:33:04.045215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:03.04817Z","time spent":"994.627151ms","remote":"127.0.0.1:41338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":647,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:33:04.045531Z","caller":"traceutil/trace.go:171","msg":"trace[1930924829] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"998.012161ms","start":"2026-04-08T00:33:03.047493Z","end":"2026-04-08T00:33:04.045505Z","steps":["trace[1930924829] 'process raft request' (duration: 993.629398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:04.04562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:03.047464Z","time spent":"998.119795ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":983,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:33:04.044979Z","caller":"traceutil/trace.go:171","msg":"trace[1896671784] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:39; }","duration":"888.326025ms","start":"2026-04-08T00:33:03.156638Z","end":"2026-04-08T00:33:04.044964Z","steps":["trace[1896671784] 'agreement among raft nodes before linearized reading' (duration: 886.388101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:04.046138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:03.156619Z","time spent":"889.504953ms","remote":"127.0.0.1:41278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-08T00:33:04.610133Z","caller":"traceutil/trace.go:171","msg":"trace[2050206638] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"562.046784ms","start":"2026-04-08T00:33:04.048051Z","end":"2026-04-08T00:33:04.610098Z","steps":["trace[2050206638] 'process raft request' (duration: 556.209153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:04.610273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.980111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T00:33:04.610323Z","caller":"traceutil/trace.go:171","msg":"trace[801885399] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:44; }","duration":"562.081945ms","start":"2026-04-08T00:33:04.048232Z","end":"2026-04-08T00:33:04.610313Z","steps":["trace[801885399] 'agreement among raft nodes before linearized reading' (duration: 561.96702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:04.610364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:04.048221Z","time spent":"562.134556ms","remote":"127.0.0.1:41260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"info","ts":"2026-04-08T00:33:04.610456Z","caller":"traceutil/trace.go:171","msg":"trace[1819512123] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"561.651482ms","start":"2026-04-08T00:33:04.048781Z","end":"2026-04-08T00:33:04.610432Z","steps":["trace[1819512123] 'process raft request' (duration: 561.186556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:04.610468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:04.048032Z","time spent":"562.244161ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:33:04.61058Z","caller":"traceutil/trace.go:171","msg":"trace[13377417] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"560.494293ms","start":"2026-04-08T00:33:04.050075Z","end":"2026-04-08T00:33:04.61057Z","steps":["trace[13377417] 'process raft request' (duration: 559.961965ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:04.610711Z","caller":"traceutil/trace.go:171","msg":"trace[210394236] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"560.965228ms","start":"2026-04-08T00:33:04.049536Z","end":"2026-04-08T00:33:04.610501Z","steps":["trace[210394236] 'process raft request' (duration: 560.472501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:04.61056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:04.04877Z","time spent":"561.735284ms","remote":"127.0.0.1:41268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:33:04.610897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:04.050068Z","time spent":"560.577245ms","remote":"127.0.0.1:41338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1058,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:33:04.610173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:04.04848Z","time spent":"561.686231ms","remote":"127.0.0.1:40954","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-08T00:33:04.610933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:04.049522Z","time spent":"561.262207ms","remote":"127.0.0.1:41338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:33:04.61013Z","caller":"traceutil/trace.go:171","msg":"trace[334341106] linearizableReadLoop","detail":"{readStateIndex:45; appliedIndex:44; }","duration":"561.836277ms","start":"2026-04-08T00:33:04.048273Z","end":"2026-04-08T00:33:04.610109Z","steps":["trace[334341106] 'read index received' (duration: 26.675525ms)","trace[334341106] 'applied index is now lower than readState.Index' (duration: 535.159862ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:33:04.611281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.173908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-08T00:33:04.611308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.892451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-04-08T00:33:04.611365Z","caller":"traceutil/trace.go:171","msg":"trace[1719659561] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:44; }","duration":"562.959293ms","start":"2026-04-08T00:33:04.04839Z","end":"2026-04-08T00:33:04.611349Z","steps":["trace[1719659561] 'agreement among raft nodes before linearized reading' (duration: 562.827089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:04.611416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:04.048368Z","time spent":"563.040246ms","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3513,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-08T00:33:04.612818Z","caller":"traceutil/trace.go:171","msg":"trace[1866212507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:44; }","duration":"562.259991ms","start":"2026-04-08T00:33:04.049063Z","end":"2026-04-08T00:33:04.611323Z","steps":["trace[1866212507] 'agreement among raft nodes before linearized reading' (duration: 562.177408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:04.612912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:04.049052Z","time spent":"563.842122ms","remote":"127.0.0.1:40876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T00:33:31.768823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.44455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:33:31.769003Z","caller":"traceutil/trace.go:171","msg":"trace[1050404921] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"350.664898ms","start":"2026-04-08T00:33:31.418318Z","end":"2026-04-08T00:33:31.768982Z","steps":["trace[1050404921] 'process raft request' (duration: 350.593606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:31.769055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:31.418289Z","time spent":"350.745559ms","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:33:31.76903Z","caller":"traceutil/trace.go:171","msg":"trace[1962546049] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"357.948262ms","start":"2026-04-08T00:33:31.411049Z","end":"2026-04-08T00:33:31.768997Z","steps":["trace[1962546049] 'process raft request' (duration: 123.274876ms)","trace[1962546049] 'compare' (duration: 234.331817ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:33:31.769281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:31.411031Z","time spent":"358.178698ms","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:33:31.811527Z","caller":"traceutil/trace.go:171","msg":"trace[489676053] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:553; }","duration":"358.650591ms","start":"2026-04-08T00:33:31.452849Z","end":"2026-04-08T00:33:31.811499Z","steps":["trace[489676053] 'read index received' (duration: 81.564007ms)","trace[489676053] 'applied index is now lower than readState.Index' (duration: 277.085314ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:31.811616Z","caller":"traceutil/trace.go:171","msg":"trace[116147368] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"361.114117ms","start":"2026-04-08T00:33:31.450472Z","end":"2026-04-08T00:33:31.811586Z","steps":["trace[116147368] 'process raft request' (duration: 360.788709ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:31.811678Z","caller":"traceutil/trace.go:171","msg":"trace[409721792] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"310.363127ms","start":"2026-04-08T00:33:31.501277Z","end":"2026-04-08T00:33:31.81164Z","steps":["trace[409721792] 'process raft request' (duration: 310.141732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:31.811726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.850286ms","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-08T00:33:31.81176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:31.45045Z","time spent":"361.232179ms","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:33:31.811773Z","caller":"traceutil/trace.go:171","msg":"trace[892453277] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:539; }","duration":"358.936248ms","start":"2026-04-08T00:33:31.452823Z","end":"2026-04-08T00:33:31.811759Z","steps":["trace[892453277] 'agreement among raft nodes before linearized reading' (duration: 358.807094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:31.811821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:31.501241Z","time spent":"310.512742ms","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:33:31.811824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:31.452814Z","time spent":"358.999811ms","remote":"127.0.0.1:41186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T00:33:32.312444Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409581030448262,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T00:33:32.812803Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409581030448262,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T00:33:33.149598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.528013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:33:33.150255Z","caller":"traceutil/trace.go:171","msg":"trace[1039083880] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.284428622s","start":"2026-04-08T00:33:31.865804Z","end":"2026-04-08T00:33:33.150233Z","steps":["trace[1039083880] 'process raft request' (duration: 1.284193036s)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:33.150301Z","caller":"traceutil/trace.go:171","msg":"trace[1603942482] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.33555461s","start":"2026-04-08T00:33:31.814739Z","end":"2026-04-08T00:33:33.150293Z","steps":["trace[1603942482] 'process raft request' (duration: 1.334986616s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:33.150364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:31.814722Z","time spent":"1.335604232s","remote":"127.0.0.1:41186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:33:33.150361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:31.86578Z","time spent":"1.284535404s","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:33:33.150258Z","caller":"traceutil/trace.go:171","msg":"trace[343564388] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.367280634s","start":"2026-04-08T00:33:31.782955Z","end":"2026-04-08T00:33:33.150236Z","steps":["trace[343564388] 'process raft request' (duration: 1.176012621s)","trace[343564388] 'compare' (duration: 190.171054ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:33.150519Z","caller":"traceutil/trace.go:171","msg":"trace[1029126837] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:557; }","duration":"1.33888977s","start":"2026-04-08T00:33:31.811601Z","end":"2026-04-08T00:33:33.15049Z","steps":["trace[1029126837] 'read index received' (duration: 1.147467423s)","trace[1029126837] 'applied index is now lower than readState.Index' (duration: 191.421067ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:33.150591Z","caller":"traceutil/trace.go:171","msg":"trace[1137681851] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.284441392s","start":"2026-04-08T00:33:31.86614Z","end":"2026-04-08T00:33:33.150581Z","steps":["trace[1137681851] 'process raft request' (duration: 1.284047262s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:33.150607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:31.782938Z","time spent":"1.367592023s","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:33:33.150662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:31.866109Z","time spent":"1.284505864s","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:33:33.150615Z","caller":"traceutil/trace.go:171","msg":"trace[422195774] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.330085755s","start":"2026-04-08T00:33:31.820474Z","end":"2026-04-08T00:33:33.15056Z","steps":["trace[422195774] 'process raft request' (duration: 1.329339255s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:33.150772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.428160582s","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-08T00:33:33.150787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.236712863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:33:33.15082Z","caller":"traceutil/trace.go:171","msg":"trace[1493135569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"1.236785115s","start":"2026-04-08T00:33:31.914026Z","end":"2026-04-08T00:33:33.150811Z","steps":["trace[1493135569] 'agreement among raft nodes before linearized reading' (duration: 1.236736904s)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:33.150821Z","caller":"traceutil/trace.go:171","msg":"trace[1781987813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:544; }","duration":"1.428249014s","start":"2026-04-08T00:33:31.722558Z","end":"2026-04-08T00:33:33.150807Z","steps":["trace[1781987813] 'agreement among raft nodes before linearized reading' (duration: 1.428118221s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:33.150846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:31.914004Z","time spent":"1.236836407s","remote":"127.0.0.1:40874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T00:33:33.150852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.063915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-08T00:33:33.150863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:31.820463Z","time spent":"1.33027307s","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:33:33.150898Z","caller":"traceutil/trace.go:171","msg":"trace[1028212438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:544; }","duration":"612.155847ms","start":"2026-04-08T00:33:32.538728Z","end":"2026-04-08T00:33:33.150884Z","steps":["trace[1028212438] 'agreement among raft nodes before linearized reading' (duration: 612.061655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:33.150942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:31.722544Z","time spent":"1.428305515s","remote":"127.0.0.1:41186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T00:33:33.150952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:32.538708Z","time spent":"612.232808ms","remote":"127.0.0.1:41186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T00:33:33.463734Z","caller":"traceutil/trace.go:171","msg":"trace[694116997] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"304.599154ms","start":"2026-04-08T00:33:33.159098Z","end":"2026-04-08T00:33:33.463697Z","steps":["trace[694116997] 'process raft request' (duration: 212.000824ms)","trace[694116997] 'compare' (duration: 92.417365ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:33.463862Z","caller":"traceutil/trace.go:171","msg":"trace[381642703] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:563; }","duration":"300.488594ms","start":"2026-04-08T00:33:33.163356Z","end":"2026-04-08T00:33:33.463845Z","steps":["trace[381642703] 'read index received' (duration: 5.919947ms)","trace[381642703] 'applied index is now lower than readState.Index' (duration: 294.567377ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:33:33.463903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:33.159087Z","time spent":"304.730748ms","remote":"127.0.0.1:41186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:33:33.463936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.571156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:33:33.463964Z","caller":"traceutil/trace.go:171","msg":"trace[1957451832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"300.632078ms","start":"2026-04-08T00:33:33.163323Z","end":"2026-04-08T00:33:33.463955Z","steps":["trace[1957451832] 'agreement among raft nodes before linearized reading' (duration: 300.577017ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:33.463988Z","caller":"traceutil/trace.go:171","msg":"trace[670150755] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"304.83748ms","start":"2026-04-08T00:33:33.159125Z","end":"2026-04-08T00:33:33.463962Z","steps":["trace[670150755] 'process raft request' (duration: 304.517202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:33.464059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:33.163308Z","time spent":"300.67765ms","remote":"127.0.0.1:40874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T00:33:33.464129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:33.159112Z","time spent":"304.938734ms","remote":"127.0.0.1:41186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:33:33.501288Z","caller":"traceutil/trace.go:171","msg":"trace[93271585] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"324.798932ms","start":"2026-04-08T00:33:33.176471Z","end":"2026-04-08T00:33:33.50127Z","steps":["trace[93271585] 'process raft request' (duration: 324.688149ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:33.501427Z","caller":"traceutil/trace.go:171","msg":"trace[1427009106] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"263.073921ms","start":"2026-04-08T00:33:33.238326Z","end":"2026-04-08T00:33:33.5014Z","steps":["trace[1427009106] 'process raft request' (duration: 262.989639ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:33.501259Z","caller":"traceutil/trace.go:171","msg":"trace[1734893513] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"330.566735ms","start":"2026-04-08T00:33:33.17067Z","end":"2026-04-08T00:33:33.501237Z","steps":["trace[1734893513] 'process raft request' (duration: 330.379999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:33.501445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:33.176441Z","time spent":"324.900275ms","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:33:33.50164Z","caller":"traceutil/trace.go:171","msg":"trace[667883467] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"311.830746ms","start":"2026-04-08T00:33:33.189766Z","end":"2026-04-08T00:33:33.501597Z","steps":["trace[667883467] 'process raft request' (duration: 311.450476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:33.501758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:33.189739Z","time spent":"311.947269ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:33:33.501774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:33.170645Z","time spent":"330.951114ms","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:33:33.70459Z","caller":"traceutil/trace.go:171","msg":"trace[223467551] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"190.103541ms","start":"2026-04-08T00:33:33.514456Z","end":"2026-04-08T00:33:33.704559Z","steps":["trace[223467551] 'process raft request' (duration: 174.131647ms)","trace[223467551] 'compare' (duration: 15.843821ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:33.799046Z","caller":"traceutil/trace.go:171","msg":"trace[986116442] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"243.712516ms","start":"2026-04-08T00:33:33.55531Z","end":"2026-04-08T00:33:33.799023Z","steps":["trace[986116442] 'process raft request' (duration: 243.660904ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:33.799232Z","caller":"traceutil/trace.go:171","msg":"trace[1762552711] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"279.083825ms","start":"2026-04-08T00:33:33.520008Z","end":"2026-04-08T00:33:33.799092Z","steps":["trace[1762552711] 'process raft request' (duration: 278.778348ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:33.951337Z","caller":"traceutil/trace.go:171","msg":"trace[1865380472] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"136.510538ms","start":"2026-04-08T00:33:33.814799Z","end":"2026-04-08T00:33:33.951309Z","steps":["trace[1865380472] 'process raft request' (duration: 136.292622ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:33.962068Z","caller":"traceutil/trace.go:171","msg":"trace[318682299] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"100.671215ms","start":"2026-04-08T00:33:33.861379Z","end":"2026-04-08T00:33:33.96205Z","steps":["trace[318682299] 'process raft request' (duration: 100.628504ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:33.962263Z","caller":"traceutil/trace.go:171","msg":"trace[428872818] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"144.345265ms","start":"2026-04-08T00:33:33.817912Z","end":"2026-04-08T00:33:33.962257Z","steps":["trace[428872818] 'process raft request' (duration: 143.950255ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:34.474531Z","caller":"traceutil/trace.go:171","msg":"trace[1723478818] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"280.650308ms","start":"2026-04-08T00:33:34.193657Z","end":"2026-04-08T00:33:34.474307Z","steps":["trace[1723478818] 'process raft request' (duration: 279.313632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:34.725568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.52811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:33:34.725984Z","caller":"traceutil/trace.go:171","msg":"trace[1299737481] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"352.197059ms","start":"2026-04-08T00:33:34.373772Z","end":"2026-04-08T00:33:34.725969Z","steps":["trace[1299737481] 'process raft request' (duration: 352.014114ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:34.725989Z","caller":"traceutil/trace.go:171","msg":"trace[1977048231] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"493.057382ms","start":"2026-04-08T00:33:34.232905Z","end":"2026-04-08T00:33:34.725963Z","steps":["trace[1977048231] 'process raft request' (duration: 345.030959ms)","trace[1977048231] 'compare' (duration: 147.387596ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:33:34.726045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:34.373744Z","time spent":"352.277001ms","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:33:34.726077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:34.232877Z","time spent":"493.154045ms","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:33:34.999259Z","caller":"traceutil/trace.go:171","msg":"trace[862022724] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"255.1681ms","start":"2026-04-08T00:33:34.744059Z","end":"2026-04-08T00:33:34.999227Z","steps":["trace[862022724] 'process raft request' (duration: 251.345359ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:35.034863Z","caller":"traceutil/trace.go:171","msg":"trace[623197651] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"252.155151ms","start":"2026-04-08T00:33:34.782684Z","end":"2026-04-08T00:33:35.034839Z","steps":["trace[623197651] 'process raft request' (duration: 251.661669ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:35.034991Z","caller":"traceutil/trace.go:171","msg":"trace[1279598022] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:585; }","duration":"120.917983ms","start":"2026-04-08T00:33:34.914025Z","end":"2026-04-08T00:33:35.034943Z","steps":["trace[1279598022] 'read index received' (duration: 81.359112ms)","trace[1279598022] 'applied index is now lower than readState.Index' (duration: 39.55776ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:35.035067Z","caller":"traceutil/trace.go:171","msg":"trace[827289900] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"220.352396ms","start":"2026-04-08T00:33:34.814699Z","end":"2026-04-08T00:33:35.035052Z","steps":["trace[827289900] 'process raft request' (duration: 219.920994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:35.035178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.124519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:33:35.035224Z","caller":"traceutil/trace.go:171","msg":"trace[1976138301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"121.201391ms","start":"2026-04-08T00:33:34.91401Z","end":"2026-04-08T00:33:35.035211Z","steps":["trace[1976138301] 'agreement among raft nodes before linearized reading' (duration: 121.069508ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:35.035522Z","caller":"traceutil/trace.go:171","msg":"trace[1234037835] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"220.601842ms","start":"2026-04-08T00:33:34.814909Z","end":"2026-04-08T00:33:35.03551Z","steps":["trace[1234037835] 'process raft request' (duration: 219.982385ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:35.168869Z","caller":"traceutil/trace.go:171","msg":"trace[491658867] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"142.584569ms","start":"2026-04-08T00:33:35.02625Z","end":"2026-04-08T00:33:35.168834Z","steps":["trace[491658867] 'process raft request' (duration: 138.13119ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:35.168928Z","caller":"traceutil/trace.go:171","msg":"trace[1009328039] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"132.504732ms","start":"2026-04-08T00:33:35.036365Z","end":"2026-04-08T00:33:35.16887Z","steps":["trace[1009328039] 'read index received' (duration: 127.899429ms)","trace[1009328039] 'applied index is now lower than readState.Index' (duration: 4.599693ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:35.168884Z","caller":"traceutil/trace.go:171","msg":"trace[1347517340] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"111.088023ms","start":"2026-04-08T00:33:35.057771Z","end":"2026-04-08T00:33:35.168859Z","steps":["trace[1347517340] 'process raft request' (duration: 111.032621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:35.169064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.676096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:33:35.169147Z","caller":"traceutil/trace.go:171","msg":"trace[872205022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"132.80453ms","start":"2026-04-08T00:33:35.03632Z","end":"2026-04-08T00:33:35.169124Z","steps":["trace[872205022] 'agreement among raft nodes before linearized reading' (duration: 132.622165ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:35.328289Z","caller":"traceutil/trace.go:171","msg":"trace[656759275] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"121.435937ms","start":"2026-04-08T00:33:35.206824Z","end":"2026-04-08T00:33:35.32826Z","steps":["trace[656759275] 'process raft request' (duration: 75.533978ms)","trace[656759275] 'compare' (duration: 45.734645ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:35.333477Z","caller":"traceutil/trace.go:171","msg":"trace[1845686193] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:593; }","duration":"102.664928ms","start":"2026-04-08T00:33:35.230746Z","end":"2026-04-08T00:33:35.33341Z","steps":["trace[1845686193] 'read index received' (duration: 51.628742ms)","trace[1845686193] 'applied index is now lower than readState.Index' (duration: 51.034836ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:33:35.333743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.966276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-04-08T00:33:35.333792Z","caller":"traceutil/trace.go:171","msg":"trace[819720649] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:578; }","duration":"103.062699ms","start":"2026-04-08T00:33:35.230715Z","end":"2026-04-08T00:33:35.333777Z","steps":["trace[819720649] 'agreement among raft nodes before linearized reading' (duration: 102.848753ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:35.334Z","caller":"traceutil/trace.go:171","msg":"trace[1487452487] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"114.77475ms","start":"2026-04-08T00:33:35.2192Z","end":"2026-04-08T00:33:35.333975Z","steps":["trace[1487452487] 'process raft request' (duration: 113.795673ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:41.54316Z","caller":"traceutil/trace.go:171","msg":"trace[735726521] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"318.454562ms","start":"2026-04-08T00:33:41.224651Z","end":"2026-04-08T00:33:41.543105Z","steps":["trace[735726521] 'process raft request' (duration: 317.475966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:41.543503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:41.224629Z","time spent":"318.672898ms","remote":"127.0.0.1:40974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:33:41.578366Z","caller":"traceutil/trace.go:171","msg":"trace[82294425] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"221.585519ms","start":"2026-04-08T00:33:41.356759Z","end":"2026-04-08T00:33:41.578345Z","steps":["trace[82294425] 'read index received' (duration: 185.040118ms)","trace[82294425] 'applied index is now lower than readState.Index' (duration: 36.544581ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:41.578435Z","caller":"traceutil/trace.go:171","msg":"trace[319957094] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"349.812856ms","start":"2026-04-08T00:33:41.228617Z","end":"2026-04-08T00:33:41.578429Z","steps":["trace[319957094] 'process raft request' (duration: 349.59412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:41.578502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:41.228607Z","time spent":"349.843687ms","remote":"127.0.0.1:41094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:33:41.578915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.160345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T00:33:41.578969Z","caller":"traceutil/trace.go:171","msg":"trace[1652305093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"222.252406ms","start":"2026-04-08T00:33:41.356706Z","end":"2026-04-08T00:33:41.578958Z","steps":["trace[1652305093] 'agreement among raft nodes before linearized reading' (duration: 221.951428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:42.279626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.740813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:33:42.279739Z","caller":"traceutil/trace.go:171","msg":"trace[716898856] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"695.124132ms","start":"2026-04-08T00:33:41.584596Z","end":"2026-04-08T00:33:42.27972Z","steps":["trace[716898856] 'process raft request' (duration: 409.191303ms)","trace[716898856] 'compare' (duration: 285.61163ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:33:42.279799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:41.584574Z","time spent":"695.199654ms","remote":"127.0.0.1:41186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:33:42.335867Z","caller":"traceutil/trace.go:171","msg":"trace[1727108559] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"749.885657ms","start":"2026-04-08T00:33:41.585947Z","end":"2026-04-08T00:33:42.335833Z","steps":["trace[1727108559] 'process raft request' (duration: 749.733493ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:42.335856Z","caller":"traceutil/trace.go:171","msg":"trace[346875316] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"749.942328ms","start":"2026-04-08T00:33:41.585857Z","end":"2026-04-08T00:33:42.335799Z","steps":["trace[346875316] 'process raft request' (duration: 749.693831ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:42.335967Z","caller":"traceutil/trace.go:171","msg":"trace[88082118] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:635; }","duration":"420.431352ms","start":"2026-04-08T00:33:41.915516Z","end":"2026-04-08T00:33:42.335947Z","steps":["trace[88082118] 'read index received' (duration: 78.267499ms)","trace[88082118] 'applied index is now lower than readState.Index' (duration: 342.162763ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:33:42.336036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:41.585918Z","time spent":"750.030471ms","remote":"127.0.0.1:41186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:33:42.336058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.544675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:33:42.33609Z","caller":"traceutil/trace.go:171","msg":"trace[1995088437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"420.629808ms","start":"2026-04-08T00:33:41.91545Z","end":"2026-04-08T00:33:42.33608Z","steps":["trace[1995088437] 'agreement among raft nodes before linearized reading' (duration: 420.569537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:42.336105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:41.585841Z","time spent":"750.119553ms","remote":"127.0.0.1:41186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:33:42.336126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:41.915403Z","time spent":"420.71687ms","remote":"127.0.0.1:40874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T00:33:42.336187Z","caller":"traceutil/trace.go:171","msg":"trace[1735199275] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"718.115382ms","start":"2026-04-08T00:33:41.618048Z","end":"2026-04-08T00:33:42.336163Z","steps":["trace[1735199275] 'process raft request' (duration: 717.681341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:42.336317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:41.618025Z","time spent":"718.238836ms","remote":"127.0.0.1:41242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:33:45.420941Z","caller":"traceutil/trace.go:171","msg":"trace[1914288301] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:697; }","duration":"157.489385ms","start":"2026-04-08T00:33:45.263429Z","end":"2026-04-08T00:33:45.420918Z","steps":["trace[1914288301] 'read index received' (duration: 133.314492ms)","trace[1914288301] 'applied index is now lower than readState.Index' (duration: 24.173843ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:45.420988Z","caller":"traceutil/trace.go:171","msg":"trace[1593055959] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"171.421946ms","start":"2026-04-08T00:33:45.249536Z","end":"2026-04-08T00:33:45.420958Z","steps":["trace[1593055959] 'process raft request' (duration: 147.262793ms)","trace[1593055959] 'compare' (duration: 23.910235ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:45.421018Z","caller":"traceutil/trace.go:171","msg":"trace[285527880] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"166.576157ms","start":"2026-04-08T00:33:45.254418Z","end":"2026-04-08T00:33:45.420994Z","steps":["trace[285527880] 'process raft request' (duration: 166.435032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:45.421081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.633809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:33:45.421125Z","caller":"traceutil/trace.go:171","msg":"trace[146870880] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:679; }","duration":"157.72943ms","start":"2026-04-08T00:33:45.263382Z","end":"2026-04-08T00:33:45.421112Z","steps":["trace[146870880] 'agreement among raft nodes before linearized reading' (duration: 157.633638ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:45.609464Z","caller":"traceutil/trace.go:171","msg":"trace[556272251] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:699; }","duration":"145.857676ms","start":"2026-04-08T00:33:45.463541Z","end":"2026-04-08T00:33:45.609398Z","steps":["trace[556272251] 'read index received' (duration: 139.388765ms)","trace[556272251] 'applied index is now lower than readState.Index' (duration: 6.467611ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:33:45.609867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.079421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-04-08T00:33:45.609924Z","caller":"traceutil/trace.go:171","msg":"trace[214626455] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:679; }","duration":"146.39689ms","start":"2026-04-08T00:33:45.463511Z","end":"2026-04-08T00:33:45.609907Z","steps":["trace[214626455] 'agreement among raft nodes before linearized reading' (duration: 145.99852ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:46.401767Z","caller":"traceutil/trace.go:171","msg":"trace[474441871] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"137.203716ms","start":"2026-04-08T00:33:46.264538Z","end":"2026-04-08T00:33:46.401742Z","steps":["trace[474441871] 'process raft request' (duration: 107.063494ms)","trace[474441871] 'compare' (duration: 29.869144ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:46.401951Z","caller":"traceutil/trace.go:171","msg":"trace[1213621113] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:705; }","duration":"108.610736ms","start":"2026-04-08T00:33:46.29333Z","end":"2026-04-08T00:33:46.401941Z","steps":["trace[1213621113] 'read index received' (duration: 78.267079ms)","trace[1213621113] 'applied index is now lower than readState.Index' (duration: 30.342377ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:46.401969Z","caller":"traceutil/trace.go:171","msg":"trace[1822383843] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"135.383908ms","start":"2026-04-08T00:33:46.266564Z","end":"2026-04-08T00:33:46.401948Z","steps":["trace[1822383843] 'process raft request' (duration: 135.07127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:46.402141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.799551ms","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-08T00:33:46.40218Z","caller":"traceutil/trace.go:171","msg":"trace[1524039127] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:686; }","duration":"108.868083ms","start":"2026-04-08T00:33:46.293303Z","end":"2026-04-08T00:33:46.402171Z","steps":["trace[1524039127] 'agreement among raft nodes before linearized reading' (duration: 108.712149ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:46.621353Z","caller":"traceutil/trace.go:171","msg":"trace[201057197] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"214.48195ms","start":"2026-04-08T00:33:46.406848Z","end":"2026-04-08T00:33:46.62133Z","steps":["trace[201057197] 'process raft request' (duration: 214.268004ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:46.621816Z","caller":"traceutil/trace.go:171","msg":"trace[30397745] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"209.856137ms","start":"2026-04-08T00:33:46.411937Z","end":"2026-04-08T00:33:46.621793Z","steps":["trace[30397745] 'process raft request' (duration: 209.349413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:46.621994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.671547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-04-08T00:33:46.62185Z","caller":"traceutil/trace.go:171","msg":"trace[1705149668] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:708; }","duration":"158.531283ms","start":"2026-04-08T00:33:46.463305Z","end":"2026-04-08T00:33:46.621836Z","steps":["trace[1705149668] 'read index received' (duration: 157.775043ms)","trace[1705149668] 'applied index is now lower than readState.Index' (duration: 755.31µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:46.62204Z","caller":"traceutil/trace.go:171","msg":"trace[1466529992] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:690; }","duration":"158.730448ms","start":"2026-04-08T00:33:46.4633Z","end":"2026-04-08T00:33:46.62203Z","steps":["trace[1466529992] 'agreement among raft nodes before linearized reading' (duration: 158.574674ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:46.622102Z","caller":"traceutil/trace.go:171","msg":"trace[1078479074] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"206.167718ms","start":"2026-04-08T00:33:46.415911Z","end":"2026-04-08T00:33:46.622078Z","steps":["trace[1078479074] 'process raft request' (duration: 205.639015ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:46.789364Z","caller":"traceutil/trace.go:171","msg":"trace[1796811170] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"152.308817ms","start":"2026-04-08T00:33:46.637028Z","end":"2026-04-08T00:33:46.789337Z","steps":["trace[1796811170] 'process raft request' (duration: 147.732496ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:46.97591Z","caller":"traceutil/trace.go:171","msg":"trace[2052558492] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:712; }","duration":"111.840632ms","start":"2026-04-08T00:33:46.864046Z","end":"2026-04-08T00:33:46.975887Z","steps":["trace[2052558492] 'read index received' (duration: 43.6795ms)","trace[2052558492] 'applied index is now lower than readState.Index' (duration: 68.159992ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:46.976051Z","caller":"traceutil/trace.go:171","msg":"trace[933119812] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"307.693837ms","start":"2026-04-08T00:33:46.668316Z","end":"2026-04-08T00:33:46.97601Z","steps":["trace[933119812] 'process raft request' (duration: 239.381742ms)","trace[933119812] 'compare' (duration: 68.070978ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:33:46.976138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.078848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-04-08T00:33:46.976179Z","caller":"traceutil/trace.go:171","msg":"trace[1315660903] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:692; }","duration":"112.16688ms","start":"2026-04-08T00:33:46.863997Z","end":"2026-04-08T00:33:46.976164Z","steps":["trace[1315660903] 'agreement among raft nodes before linearized reading' (duration: 112.003166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:46.976215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:46.668301Z","time spent":"307.83673ms","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:33:47.64077Z","caller":"traceutil/trace.go:171","msg":"trace[1739776258] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"263.600814ms","start":"2026-04-08T00:33:47.377151Z","end":"2026-04-08T00:33:47.640752Z","steps":["trace[1739776258] 'process raft request' (duration: 263.389099ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:47.807145Z","caller":"traceutil/trace.go:171","msg":"trace[135065877] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"400.123774ms","start":"2026-04-08T00:33:47.406983Z","end":"2026-04-08T00:33:47.807107Z","steps":["trace[135065877] 'process raft request' (duration: 399.773984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:47.807373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:47.406964Z","time spent":"400.335109ms","remote":"127.0.0.1:40954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:33:47.8074Z","caller":"traceutil/trace.go:171","msg":"trace[1312937258] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"316.158051ms","start":"2026-04-08T00:33:47.491218Z","end":"2026-04-08T00:33:47.807376Z","steps":["trace[1312937258] 'process raft request' (duration: 315.71309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:47.807704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:47.49119Z","time spent":"316.466039ms","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:33:47.80824Z","caller":"traceutil/trace.go:171","msg":"trace[695117208] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"234.598016ms","start":"2026-04-08T00:33:47.573621Z","end":"2026-04-08T00:33:47.808219Z","steps":["trace[695117208] 'process raft request' (duration: 233.564808ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:47.808246Z","caller":"traceutil/trace.go:171","msg":"trace[290961192] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:722; }","duration":"383.089812ms","start":"2026-04-08T00:33:47.425128Z","end":"2026-04-08T00:33:47.808218Z","steps":["trace[290961192] 'read index received' (duration: 215.485867ms)","trace[290961192] 'applied index is now lower than readState.Index' (duration: 167.499291ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:33:47.808626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.364608ms","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-08T00:33:47.808684Z","caller":"traceutil/trace.go:171","msg":"trace[1067647546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:705; }","duration":"383.596744ms","start":"2026-04-08T00:33:47.425073Z","end":"2026-04-08T00:33:47.80867Z","steps":["trace[1067647546] 'agreement among raft nodes before linearized reading' (duration: 383.222715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:47.808733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.936321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"warn","ts":"2026-04-08T00:33:47.808792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.952292ms","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-08T00:33:47.808859Z","caller":"traceutil/trace.go:171","msg":"trace[1448987066] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:705; }","duration":"110.057634ms","start":"2026-04-08T00:33:47.698785Z","end":"2026-04-08T00:33:47.808843Z","steps":["trace[1448987066] 'agreement among raft nodes before linearized reading' (duration: 109.836469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:47.808727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:47.425055Z","time spent":"383.658925ms","remote":"127.0.0.1:41186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T00:33:47.809126Z","caller":"traceutil/trace.go:171","msg":"trace[907981989] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:705; }","duration":"145.351752ms","start":"2026-04-08T00:33:47.663741Z","end":"2026-04-08T00:33:47.809093Z","steps":["trace[907981989] 'agreement among raft nodes before linearized reading' (duration: 144.797698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:58.631395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.777298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-bzln4\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-08T00:33:58.631518Z","caller":"traceutil/trace.go:171","msg":"trace[418414142] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-bzln4; range_end:; response_count:1; response_revision:786; }","duration":"314.036515ms","start":"2026-04-08T00:33:58.317425Z","end":"2026-04-08T00:33:58.631462Z","steps":["trace[418414142] 'range keys from in-memory index tree' (duration: 313.583824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:58.631614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:58.317406Z","time spent":"314.197069ms","remote":"127.0.0.1:41094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-bzln4\" "} {"level":"warn","ts":"2026-04-08T00:33:58.631935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.927043ms","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-08T00:33:58.632035Z","caller":"traceutil/trace.go:171","msg":"trace[1593136670] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:786; }","duration":"133.105637ms","start":"2026-04-08T00:33:58.498906Z","end":"2026-04-08T00:33:58.632012Z","steps":["trace[1593136670] 'range keys from in-memory index tree' (duration: 132.327806ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:58.92371Z","caller":"traceutil/trace.go:171","msg":"trace[624993230] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"287.515671ms","start":"2026-04-08T00:33:58.636175Z","end":"2026-04-08T00:33:58.923691Z","steps":["trace[624993230] 'process raft request' (duration: 287.393226ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:59.067278Z","caller":"traceutil/trace.go:171","msg":"trace[2027914109] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:810; }","duration":"294.110956ms","start":"2026-04-08T00:33:58.773145Z","end":"2026-04-08T00:33:59.067256Z","steps":["trace[2027914109] 'read index received' (duration: 150.493889ms)","trace[2027914109] 'applied index is now lower than readState.Index' (duration: 143.616197ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:33:59.067523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.345481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T00:33:59.067356Z","caller":"traceutil/trace.go:171","msg":"trace[1980375552] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"427.88585ms","start":"2026-04-08T00:33:58.639435Z","end":"2026-04-08T00:33:59.067321Z","steps":["trace[1980375552] 'process raft request' (duration: 427.694604ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:33:59.067607Z","caller":"traceutil/trace.go:171","msg":"trace[1405409521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:788; }","duration":"294.481355ms","start":"2026-04-08T00:33:58.773104Z","end":"2026-04-08T00:33:59.067586Z","steps":["trace[1405409521] 'agreement among raft nodes before linearized reading' (duration: 294.235119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:59.067735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.298944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:33:59.067814Z","caller":"traceutil/trace.go:171","msg":"trace[152332000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"153.425977ms","start":"2026-04-08T00:33:58.91437Z","end":"2026-04-08T00:33:59.067796Z","steps":["trace[152332000] 'agreement among raft nodes before linearized reading' (duration: 153.294984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:59.067979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:33:58.639413Z","time spent":"428.198899ms","remote":"127.0.0.1:41094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:33:59.068291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.689628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T00:33:59.06841Z","caller":"traceutil/trace.go:171","msg":"trace[2091839136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"292.855752ms","start":"2026-04-08T00:33:58.775538Z","end":"2026-04-08T00:33:59.068393Z","steps":["trace[2091839136] 'agreement among raft nodes before linearized reading' (duration: 292.02053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:33.611075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.432605ms","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-08T00:34:33.611164Z","caller":"traceutil/trace.go:171","msg":"trace[427459169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1001; }","duration":"384.566728ms","start":"2026-04-08T00:34:33.226579Z","end":"2026-04-08T00:34:33.611145Z","steps":["trace[427459169] 'range keys from in-memory index tree' (duration: 384.256681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:33.611203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:33.226558Z","time spent":"384.636009ms","remote":"127.0.0.1:41186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T00:34:33.611504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.715553ms","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-08T00:34:33.611562Z","caller":"traceutil/trace.go:171","msg":"trace[1442861791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1001; }","duration":"384.808105ms","start":"2026-04-08T00:34:33.226737Z","end":"2026-04-08T00:34:33.611545Z","steps":["trace[1442861791] 'range keys from in-memory index tree' (duration: 384.589389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:33.611627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:33.226708Z","time spent":"384.908858ms","remote":"127.0.0.1:41186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T00:34:54.559691Z","caller":"traceutil/trace.go:171","msg":"trace[1235914757] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"373.479353ms","start":"2026-04-08T00:34:54.186166Z","end":"2026-04-08T00:34:54.559645Z","steps":["trace[1235914757] 'process raft request' (duration: 293.539521ms)","trace[1235914757] 'compare' (duration: 79.707305ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:34:54.559912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:54.18614Z","time spent":"373.654308ms","remote":"127.0.0.1:41186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:34:55.574501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.612271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T00:34:55.574595Z","caller":"traceutil/trace.go:171","msg":"trace[2031204269] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:1073; }","duration":"140.729153ms","start":"2026-04-08T00:34:55.433832Z","end":"2026-04-08T00:34:55.574562Z","steps":["trace[2031204269] 'range keys from in-memory index tree' (duration: 140.462005ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:55.953586Z","caller":"traceutil/trace.go:171","msg":"trace[1135514422] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"246.733602ms","start":"2026-04-08T00:34:55.706823Z","end":"2026-04-08T00:34:55.953556Z","steps":["trace[1135514422] 'process raft request' (duration: 246.550047ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:56.064898Z","caller":"traceutil/trace.go:171","msg":"trace[635433285] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1113; }","duration":"286.75298ms","start":"2026-04-08T00:34:55.778125Z","end":"2026-04-08T00:34:56.064878Z","steps":["trace[635433285] 'read index received' (duration: 175.956944ms)","trace[635433285] 'applied index is now lower than readState.Index' (duration: 110.794896ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:56.064996Z","caller":"traceutil/trace.go:171","msg":"trace[1693541753] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"357.775694ms","start":"2026-04-08T00:34:55.707183Z","end":"2026-04-08T00:34:56.064959Z","steps":["trace[1693541753] 'process raft request' (duration: 282.575088ms)","trace[1693541753] 'compare' (duration: 75.007911ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:34:56.065108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.973715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T00:34:56.065141Z","caller":"traceutil/trace.go:171","msg":"trace[1029982924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1075; }","duration":"287.052617ms","start":"2026-04-08T00:34:55.77808Z","end":"2026-04-08T00:34:56.065133Z","steps":["trace[1029982924] 'agreement among raft nodes before linearized reading' (duration: 286.919274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:56.065187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:55.707173Z","time spent":"357.905518ms","remote":"127.0.0.1:40916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:34:56.065384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.766653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T00:34:56.065413Z","caller":"traceutil/trace.go:171","msg":"trace[1327378031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1075; }","duration":"285.815085ms","start":"2026-04-08T00:34:55.77959Z","end":"2026-04-08T00:34:56.065405Z","steps":["trace[1327378031] 'agreement among raft nodes before linearized reading' (duration: 285.752132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:56.065563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.747182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:56.065584Z","caller":"traceutil/trace.go:171","msg":"trace[990033135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1075; }","duration":"150.802303ms","start":"2026-04-08T00:34:55.914776Z","end":"2026-04-08T00:34:56.065578Z","steps":["trace[990033135] 'agreement among raft nodes before linearized reading' (duration: 150.768573ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:56.455852Z","caller":"traceutil/trace.go:171","msg":"trace[1974302427] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1114; }","duration":"384.355223ms","start":"2026-04-08T00:34:56.071469Z","end":"2026-04-08T00:34:56.455824Z","steps":["trace[1974302427] 'read index received' (duration: 77.437396ms)","trace[1974302427] 'applied index is now lower than readState.Index' (duration: 306.916477ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:56.455977Z","caller":"traceutil/trace.go:171","msg":"trace[1393342819] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"385.042451ms","start":"2026-04-08T00:34:56.070922Z","end":"2026-04-08T00:34:56.455964Z","steps":["trace[1393342819] 'process raft request' (duration: 313.433551ms)","trace[1393342819] 'compare' (duration: 71.265151ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:34:56.456114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:56.070906Z","time spent":"385.126884ms","remote":"127.0.0.1:41186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:34:56.456184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.482831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:34:56.456211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.716713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-08T00:34:56.456237Z","caller":"traceutil/trace.go:171","msg":"trace[275324227] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1077; }","duration":"169.576464ms","start":"2026-04-08T00:34:56.286646Z","end":"2026-04-08T00:34:56.456223Z","steps":["trace[275324227] 'agreement among raft nodes before linearized reading' (duration: 169.479061ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:56.456273Z","caller":"traceutil/trace.go:171","msg":"trace[1965817643] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1077; }","duration":"384.809115ms","start":"2026-04-08T00:34:56.071447Z","end":"2026-04-08T00:34:56.456256Z","steps":["trace[1965817643] 'agreement among raft nodes before linearized reading' (duration: 384.671292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:56.456306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:56.071438Z","time spent":"384.861857ms","remote":"127.0.0.1:41198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-04-08T00:34:56.456293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.222775ms","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-08T00:34:56.456382Z","caller":"traceutil/trace.go:171","msg":"trace[138670850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1077; }","duration":"196.342877ms","start":"2026-04-08T00:34:56.260006Z","end":"2026-04-08T00:34:56.456348Z","steps":["trace[138670850] 'agreement among raft nodes before linearized reading' (duration: 196.118031ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:57.392604Z","caller":"traceutil/trace.go:171","msg":"trace[1174478969] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"113.684242ms","start":"2026-04-08T00:34:57.278897Z","end":"2026-04-08T00:34:57.392581Z","steps":["trace[1174478969] 'process raft request' (duration: 113.547658ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:09.699321Z","caller":"traceutil/trace.go:171","msg":"trace[78298553] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"248.235772ms","start":"2026-04-08T00:35:09.451061Z","end":"2026-04-08T00:35:09.699297Z","steps":["trace[78298553] 'process raft request' (duration: 245.609192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:26.717574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.180516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T00:35:26.717707Z","caller":"traceutil/trace.go:171","msg":"trace[256423369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1252; }","duration":"108.365041ms","start":"2026-04-08T00:35:26.609323Z","end":"2026-04-08T00:35:26.717689Z","steps":["trace[256423369] 'range keys from in-memory index tree' (duration: 108.006081ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:28.227007Z","caller":"traceutil/trace.go:171","msg":"trace[770391609] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"128.156898ms","start":"2026-04-08T00:35:28.098824Z","end":"2026-04-08T00:35:28.226981Z","steps":["trace[770391609] 'process raft request' (duration: 127.929342ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:28.541944Z","caller":"traceutil/trace.go:171","msg":"trace[345245338] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"114.755992ms","start":"2026-04-08T00:35:28.427161Z","end":"2026-04-08T00:35:28.541917Z","steps":["trace[345245338] 'process raft request' (duration: 114.615058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:38.996492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.128408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:35:38.996735Z","caller":"traceutil/trace.go:171","msg":"trace[348117039] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"187.538054ms","start":"2026-04-08T00:35:38.809171Z","end":"2026-04-08T00:35:38.996709Z","steps":["trace[348117039] 'process raft request' (duration: 19.082298ms)","trace[348117039] 'compare' (duration: 167.996844ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:35:38.997033Z","caller":"traceutil/trace.go:171","msg":"trace[1900888969] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"187.839072ms","start":"2026-04-08T00:35:38.809169Z","end":"2026-04-08T00:35:38.997008Z","steps":["trace[1900888969] 'process raft request' (duration: 187.451602ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:38.997112Z","caller":"traceutil/trace.go:171","msg":"trace[1119753385] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"187.586566ms","start":"2026-04-08T00:35:38.809505Z","end":"2026-04-08T00:35:38.997091Z","steps":["trace[1119753385] 'process raft request' (duration: 187.407551ms)"],"step_count":1}