{"level":"warn","ts":"2026-03-06T00:28:17.959576Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T00:28:17.959655Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-06T00:28:17.959716Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T00:28:17.959724Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-03-06T00:28:17.959743Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-06T00:28:17.960347Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-03-06T00:28:17.960414Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-06T00:28:17.965114Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.381604ms"} {"level":"info","ts":"2026-03-06T00:28:17.977341Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-03-06T00:28:17.977421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-03-06T00:28:17.977458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-03-06T00:28:17.977472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-06T00:28:17.977481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-03-06T00:28:17.977523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-03-06T00:28:17.987816Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-06T00:28:18.00212Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-06T00:28:18.026716Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-06T00:28:18.197408Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-06T00:28:18.197688Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-06T00:28:18.197686Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-06T00:28:18.197812Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-06T00:28:18.197835Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"warn","ts":"2026-03-06T00:28:18.198482Z","logger":"raft","caller":"etcdserver/zap_raft.go:85","msg":"f3d52791b64f7591 cannot campaign at term 1 since there are still 1 pending configuration changes to apply"} {"level":"info","ts":"2026-03-06T00:28:18.203109Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-06T00:28:18.203229Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-03-06T00:28:18.203278Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-03-06T00:28:18.203957Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-06T00:28:18.204002Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-06T00:28:18.231577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-03-06T00:28:18.231797Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-03-06T00:28:19.178493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-03-06T00:28:19.178543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-06T00:28:19.178582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-03-06T00:28:19.178597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-03-06T00:28:19.178605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-03-06T00:28:19.178618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-03-06T00:28:19.178626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-03-06T00:28:19.179504Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-03-06T00:28:19.179561Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T00:28:19.179945Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T00:28:19.180046Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T00:28:19.180434Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-06T00:28:19.180491Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-06T00:28:19.180866Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T00:28:19.180963Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T00:28:19.180998Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T00:28:19.182052Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-06T00:28:19.182535Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-03-06T00:28:22.630906Z","caller":"traceutil/trace.go:171","msg":"trace[803219749] transaction","detail":"{read_only:false; response_revision:151; number_of_response:1; }","duration":"101.123324ms","start":"2026-03-06T00:28:22.529736Z","end":"2026-03-06T00:28:22.630859Z","steps":["trace[803219749] 'process raft request' (duration: 21.247255ms)","trace[803219749] 'compare' (duration: 79.61046ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:22.873266Z","caller":"traceutil/trace.go:171","msg":"trace[1841504972] transaction","detail":"{read_only:false; response_revision:154; number_of_response:1; }","duration":"182.747634ms","start":"2026-03-06T00:28:22.690483Z","end":"2026-03-06T00:28:22.873231Z","steps":["trace[1841504972] 'process raft request' (duration: 128.932019ms)","trace[1841504972] 'compare' (duration: 53.540876ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:23.072312Z","caller":"traceutil/trace.go:171","msg":"trace[2135450189] transaction","detail":"{read_only:false; response_revision:158; number_of_response:1; }","duration":"117.206759ms","start":"2026-03-06T00:28:22.955066Z","end":"2026-03-06T00:28:23.072273Z","steps":["trace[2135450189] 'process raft request' (duration: 89.110024ms)","trace[2135450189] 'compare' (duration: 27.901949ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:28:23.453131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.492383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:28:23.453298Z","caller":"traceutil/trace.go:171","msg":"trace[930293166] linearizableReadLoop","detail":"{readStateIndex:165; appliedIndex:164; }","duration":"360.437949ms","start":"2026-03-06T00:28:23.092839Z","end":"2026-03-06T00:28:23.453277Z","steps":["trace[930293166] 'read index received' (duration: 103.202959ms)","trace[930293166] 'applied index is now lower than readState.Index' (duration: 257.23359ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:28:23.453488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.644945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:28:23.453533Z","caller":"traceutil/trace.go:171","msg":"trace[1364627496] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:160; }","duration":"360.797531ms","start":"2026-03-06T00:28:23.09272Z","end":"2026-03-06T00:28:23.453517Z","steps":["trace[1364627496] 'agreement among raft nodes before linearized reading' (duration: 360.677417ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:23.453525Z","caller":"traceutil/trace.go:171","msg":"trace[488965269] transaction","detail":"{read_only:false; response_revision:160; number_of_response:1; }","duration":"363.110021ms","start":"2026-03-06T00:28:23.090371Z","end":"2026-03-06T00:28:23.453481Z","steps":["trace[488965269] 'process raft request' (duration: 105.669284ms)","trace[488965269] 'compare' (duration: 256.245805ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:28:23.453578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:23.092697Z","time spent":"360.871664ms","remote":"127.0.0.1:59660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":29,"request content":"key:\"/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler\" "} {"level":"warn","ts":"2026-03-06T00:28:23.453643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.483138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T00:28:23.453756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:23.090344Z","time spent":"363.266306ms","remote":"127.0.0.1:59356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T00:28:23.453829Z","caller":"traceutil/trace.go:171","msg":"trace[1489408217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:160; }","duration":"168.688125ms","start":"2026-03-06T00:28:23.285084Z","end":"2026-03-06T00:28:23.453772Z","steps":["trace[1489408217] 'agreement among raft nodes before linearized reading' (duration: 168.402355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:24.005799Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724724849785493,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T00:28:25.133449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.020194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:28:25.133639Z","caller":"traceutil/trace.go:171","msg":"trace[1229693847] linearizableReadLoop","detail":"{readStateIndex:170; appliedIndex:167; }","duration":"1.628801604s","start":"2026-03-06T00:28:23.504824Z","end":"2026-03-06T00:28:25.133626Z","steps":["trace[1229693847] 'read index received' (duration: 811.558541ms)","trace[1229693847] 'applied index is now lower than readState.Index' (duration: 817.241913ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:25.133695Z","caller":"traceutil/trace.go:171","msg":"trace[1014341561] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"1.674988213s","start":"2026-03-06T00:28:23.4587Z","end":"2026-03-06T00:28:25.133688Z","steps":["trace[1014341561] 'process raft request' (duration: 857.668638ms)","trace[1014341561] 'compare' (duration: 810.434133ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:28:25.133734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:23.458684Z","time spent":"1.675033864s","remote":"127.0.0.1:59356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T00:28:25.133865Z","caller":"traceutil/trace.go:171","msg":"trace[528759075] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"1.526328271s","start":"2026-03-06T00:28:23.607529Z","end":"2026-03-06T00:28:25.133858Z","steps":["trace[528759075] 'process raft request' (duration: 1.526068662s)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:25.13385Z","caller":"traceutil/trace.go:171","msg":"trace[206682433] transaction","detail":"{read_only:false; response_revision:164; number_of_response:1; }","duration":"1.674924961s","start":"2026-03-06T00:28:23.458902Z","end":"2026-03-06T00:28:25.133827Z","steps":["trace[206682433] 'process raft request' (duration: 1.67462479s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:25.133903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:23.607509Z","time spent":"1.526376212s","remote":"127.0.0.1:59456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T00:28:25.133973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:23.458893Z","time spent":"1.675026444s","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T00:28:25.134045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.629222229s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:28:25.134077Z","caller":"traceutil/trace.go:171","msg":"trace[606679150] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:job-controller; range_end:; response_count:0; response_revision:165; }","duration":"1.629399005s","start":"2026-03-06T00:28:23.50467Z","end":"2026-03-06T00:28:25.134069Z","steps":["trace[606679150] 'agreement among raft nodes before linearized reading' (duration: 1.629323502s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:25.134096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:23.504644Z","time spent":"1.629448127s","remote":"127.0.0.1:59660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/clusterrolebindings/system:controller:job-controller\" "} {"level":"warn","ts":"2026-03-06T00:28:25.581362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999968683s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-06T00:28:25.581476Z","caller":"traceutil/trace.go:171","msg":"trace[1171157485] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00016551s","start":"2026-03-06T00:28:23.581288Z","end":"2026-03-06T00:28:25.581453Z","steps":["trace[1171157485] 'agreement among raft nodes before linearized reading' (duration: 2.000000824s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:25.58153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:23.58127Z","time spent":"2.000247593s","remote":"127.0.0.1:59248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:28:25.634298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724724849785500,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T00:28:25.649659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000340159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-06T00:28:25.649752Z","caller":"traceutil/trace.go:171","msg":"trace[460620496] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000510415s","start":"2026-03-06T00:28:23.649222Z","end":"2026-03-06T00:28:25.649733Z","steps":["trace[460620496] 'agreement among raft nodes before linearized reading' (duration: 2.00037948s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:25.649839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:23.649201Z","time spent":"2.000626568s","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/06 00:28:25 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-06T00:28:25.906537Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.158391554s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-06T00:28:27.422124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.51514722s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:28:27.422402Z","caller":"traceutil/trace.go:171","msg":"trace[835499727] linearizableReadLoop","detail":"{readStateIndex:173; appliedIndex:170; }","duration":"2.28871431s","start":"2026-03-06T00:28:25.133665Z","end":"2026-03-06T00:28:27.42238Z","steps":["trace[835499727] 'read index received' (duration: 773.193228ms)","trace[835499727] 'applied index is now lower than readState.Index' (duration: 1.515519672s)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:27.422534Z","caller":"traceutil/trace.go:171","msg":"trace[1109812007] transaction","detail":"{read_only:false; response_revision:168; number_of_response:1; }","duration":"2.282946058s","start":"2026-03-06T00:28:25.139575Z","end":"2026-03-06T00:28:27.422521Z","steps":["trace[1109812007] 'process raft request' (duration: 2.282757652s)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:27.422556Z","caller":"traceutil/trace.go:171","msg":"trace[790715175] transaction","detail":"{read_only:false; response_revision:166; number_of_response:1; }","duration":"2.674494276s","start":"2026-03-06T00:28:24.748021Z","end":"2026-03-06T00:28:27.422515Z","steps":["trace[790715175] 'process raft request' (duration: 1.158846509s)","trace[790715175] 'compare' (duration: 1.514795568s)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:28:27.42261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:25.139561Z","time spent":"2.28301581s","remote":"127.0.0.1:59660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T00:28:27.422679Z","caller":"traceutil/trace.go:171","msg":"trace[1583679875] transaction","detail":"{read_only:false; response_revision:167; number_of_response:1; }","duration":"2.283071423s","start":"2026-03-06T00:28:25.139579Z","end":"2026-03-06T00:28:27.42265Z","steps":["trace[1583679875] 'process raft request' (duration: 2.2826911s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:27.422715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:24.748001Z","time spent":"2.674633091s","remote":"127.0.0.1:59456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T00:28:27.422805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:25.139572Z","time spent":"2.283162896s","remote":"127.0.0.1:59356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T00:28:27.423045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.284896577s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-06T00:28:27.423066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.316950302s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:28:27.423083Z","caller":"traceutil/trace.go:171","msg":"trace[2018620750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:168; }","duration":"2.28496767s","start":"2026-03-06T00:28:25.138104Z","end":"2026-03-06T00:28:27.423071Z","steps":["trace[2018620750] 'agreement among raft nodes before linearized reading' (duration: 2.284870126s)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:27.423098Z","caller":"traceutil/trace.go:171","msg":"trace[1785685536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:168; }","duration":"2.317027444s","start":"2026-03-06T00:28:25.106062Z","end":"2026-03-06T00:28:27.423089Z","steps":["trace[1785685536] 'agreement among raft nodes before linearized reading' (duration: 2.316961092s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:27.423121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:25.13809Z","time spent":"2.285023851s","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T00:28:27.423128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:25.106044Z","time spent":"2.317079616s","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-06T00:28:27.423117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.777476339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:28:27.423163Z","caller":"traceutil/trace.go:171","msg":"trace[709670660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:168; }","duration":"2.777564541s","start":"2026-03-06T00:28:24.645588Z","end":"2026-03-06T00:28:27.423153Z","steps":["trace[709670660] 'agreement among raft nodes before linearized reading' (duration: 2.777479568s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:27.423166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.652324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"warn","ts":"2026-03-06T00:28:27.423193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:24.645566Z","time spent":"2.777620604s","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-06T00:28:27.423227Z","caller":"traceutil/trace.go:171","msg":"trace[2080726649] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:168; }","duration":"205.770989ms","start":"2026-03-06T00:28:27.217428Z","end":"2026-03-06T00:28:27.423199Z","steps":["trace[2080726649] 'agreement among raft nodes before linearized reading' (duration: 205.675776ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:27.423262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.651864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T00:28:27.423284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.673752246s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:28:27.423296Z","caller":"traceutil/trace.go:171","msg":"trace[1532620996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:168; }","duration":"349.706026ms","start":"2026-03-06T00:28:27.073576Z","end":"2026-03-06T00:28:27.423282Z","steps":["trace[1532620996] 'agreement among raft nodes before linearized reading' (duration: 349.630483ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:27.423315Z","caller":"traceutil/trace.go:171","msg":"trace[679182103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:168; }","duration":"1.673817079s","start":"2026-03-06T00:28:25.749489Z","end":"2026-03-06T00:28:27.423307Z","steps":["trace[679182103] 'agreement among raft nodes before linearized reading' (duration: 1.673773937s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:27.423327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:27.073558Z","time spent":"349.764068ms","remote":"127.0.0.1:59248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:28:27.423341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:25.749469Z","time spent":"1.67386696s","remote":"127.0.0.1:59248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:28:27.423681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.918622532s","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-03-06T00:28:27.423734Z","caller":"traceutil/trace.go:171","msg":"trace[1192060354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:168; }","duration":"3.918716305s","start":"2026-03-06T00:28:23.505006Z","end":"2026-03-06T00:28:27.423722Z","steps":["trace[1192060354] 'agreement among raft nodes before linearized reading' (duration: 3.917926538s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:27.423764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:23.504955Z","time spent":"3.918802008s","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-06T00:28:28.182877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.911307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:28:28.18318Z","caller":"traceutil/trace.go:171","msg":"trace[342536549] transaction","detail":"{read_only:false; response_revision:197; number_of_response:1; }","duration":"481.081693ms","start":"2026-03-06T00:28:27.702064Z","end":"2026-03-06T00:28:28.183146Z","steps":["trace[342536549] 'process raft request' (duration: 149.836174ms)","trace[342536549] 'compare' (duration: 330.787213ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:28.18323Z","caller":"traceutil/trace.go:171","msg":"trace[2012305359] transaction","detail":"{read_only:false; response_revision:198; number_of_response:1; }","duration":"479.017111ms","start":"2026-03-06T00:28:27.704156Z","end":"2026-03-06T00:28:28.183173Z","steps":["trace[2012305359] 'process raft request' (duration: 478.817264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:28.183392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:27.702049Z","time spent":"481.293151ms","remote":"127.0.0.1:59660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":764,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T00:28:28.183654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:27.704136Z","time spent":"479.187678ms","remote":"127.0.0.1:59356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:28:28.384408Z","caller":"traceutil/trace.go:171","msg":"trace[605916432] linearizableReadLoop","detail":"{readStateIndex:207; appliedIndex:206; }","duration":"158.863973ms","start":"2026-03-06T00:28:28.22551Z","end":"2026-03-06T00:28:28.384374Z","steps":["trace[605916432] 'read index received' (duration: 88.49998ms)","trace[605916432] 'applied index is now lower than readState.Index' (duration: 70.361843ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:28.384612Z","caller":"traceutil/trace.go:171","msg":"trace[74896987] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"180.794284ms","start":"2026-03-06T00:28:28.203776Z","end":"2026-03-06T00:28:28.38457Z","steps":["trace[74896987] 'process raft request' (duration: 110.219903ms)","trace[74896987] 'compare' (duration: 70.213298ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:28:28.384721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.177214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:28:28.384775Z","caller":"traceutil/trace.go:171","msg":"trace[1246533297] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-controller; range_end:; response_count:0; response_revision:202; }","duration":"159.309568ms","start":"2026-03-06T00:28:28.225448Z","end":"2026-03-06T00:28:28.384757Z","steps":["trace[1246533297] 'agreement among raft nodes before linearized reading' (duration: 159.143853ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:28.384873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.693314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-03-06T00:28:28.384995Z","caller":"traceutil/trace.go:171","msg":"trace[1917421523] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:202; }","duration":"101.876962ms","start":"2026-03-06T00:28:28.283099Z","end":"2026-03-06T00:28:28.384976Z","steps":["trace[1917421523] 'agreement among raft nodes before linearized reading' (duration: 101.55644ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:29.67337Z","caller":"traceutil/trace.go:171","msg":"trace[686995464] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"127.457771ms","start":"2026-03-06T00:28:29.545871Z","end":"2026-03-06T00:28:29.673329Z","steps":["trace[686995464] 'process raft request' (duration: 78.943285ms)","trace[686995464] 'compare' (duration: 48.306199ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:30.510132Z","caller":"traceutil/trace.go:171","msg":"trace[1587298462] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"129.266887ms","start":"2026-03-06T00:28:30.380815Z","end":"2026-03-06T00:28:30.510082Z","steps":["trace[1587298462] 'process raft request' (duration: 92.473153ms)","trace[1587298462] 'compare' (duration: 36.563736ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:28:30.510642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.123068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:28:30.511021Z","caller":"traceutil/trace.go:171","msg":"trace[1193110059] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:286; }","duration":"109.778651ms","start":"2026-03-06T00:28:30.40117Z","end":"2026-03-06T00:28:30.510948Z","steps":["trace[1193110059] 'agreement among raft nodes before linearized reading' (duration: 109.085767ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:30.510089Z","caller":"traceutil/trace.go:171","msg":"trace[782065130] linearizableReadLoop","detail":"{readStateIndex:292; appliedIndex:291; }","duration":"108.836529ms","start":"2026-03-06T00:28:30.401204Z","end":"2026-03-06T00:28:30.510041Z","steps":["trace[782065130] 'read index received' (duration: 72.042835ms)","trace[782065130] 'applied index is now lower than readState.Index' (duration: 36.791744ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:28:30.878096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.069843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:28:30.878481Z","caller":"traceutil/trace.go:171","msg":"trace[791220995] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"298.102137ms","start":"2026-03-06T00:28:30.580312Z","end":"2026-03-06T00:28:30.878414Z","steps":["trace[791220995] 'process raft request' (duration: 32.016727ms)","trace[791220995] 'compare' (duration: 264.815494ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:30.878731Z","caller":"traceutil/trace.go:171","msg":"trace[293681311] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:297; }","duration":"277.15361ms","start":"2026-03-06T00:28:30.601552Z","end":"2026-03-06T00:28:30.878705Z","steps":["trace[293681311] 'read index received' (duration: 10.78786ms)","trace[293681311] 'applied index is now lower than readState.Index' (duration: 266.36123ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:30.878868Z","caller":"traceutil/trace.go:171","msg":"trace[1113307467] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"291.363819ms","start":"2026-03-06T00:28:30.587485Z","end":"2026-03-06T00:28:30.878849Z","steps":["trace[1113307467] 'process raft request' (duration: 290.892452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:30.878983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.388438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-06T00:28:30.879038Z","caller":"traceutil/trace.go:171","msg":"trace[824316202] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:294; }","duration":"277.502722ms","start":"2026-03-06T00:28:30.601523Z","end":"2026-03-06T00:28:30.879026Z","steps":["trace[824316202] 'agreement among raft nodes before linearized reading' (duration: 277.263034ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:32.597273Z","caller":"traceutil/trace.go:171","msg":"trace[916623599] transaction","detail":"{read_only:false; number_of_response:0; response_revision:334; }","duration":"108.863703ms","start":"2026-03-06T00:28:32.488386Z","end":"2026-03-06T00:28:32.597249Z","steps":["trace[916623599] 'process raft request' (duration: 108.663496ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:32.597432Z","caller":"traceutil/trace.go:171","msg":"trace[111789509] transaction","detail":"{read_only:false; number_of_response:0; response_revision:334; }","duration":"107.456203ms","start":"2026-03-06T00:28:32.489949Z","end":"2026-03-06T00:28:32.597405Z","steps":["trace[111789509] 'process raft request' (duration: 107.236605ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:32.598311Z","caller":"traceutil/trace.go:171","msg":"trace[1348634465] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"103.213423ms","start":"2026-03-06T00:28:32.494733Z","end":"2026-03-06T00:28:32.597946Z","steps":["trace[1348634465] 'process raft request' (duration: 102.493498ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:32.716678Z","caller":"traceutil/trace.go:171","msg":"trace[1436903654] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:350; }","duration":"109.735043ms","start":"2026-03-06T00:28:32.606915Z","end":"2026-03-06T00:28:32.71665Z","steps":["trace[1436903654] 'read index received' (duration: 27.491946ms)","trace[1436903654] 'applied index is now lower than readState.Index' (duration: 82.241796ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:28:32.716867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.92524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-03-06T00:28:32.716904Z","caller":"traceutil/trace.go:171","msg":"trace[334861346] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:336; }","duration":"110.013383ms","start":"2026-03-06T00:28:32.606878Z","end":"2026-03-06T00:28:32.716891Z","steps":["trace[334861346] 'agreement among raft nodes before linearized reading' (duration: 109.878958ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:32.717018Z","caller":"traceutil/trace.go:171","msg":"trace[1413918038] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"111.450703ms","start":"2026-03-06T00:28:32.605508Z","end":"2026-03-06T00:28:32.716959Z","steps":["trace[1413918038] 'process raft request' (duration: 28.908537ms)","trace[1413918038] 'compare' (duration: 82.098671ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:33.033353Z","caller":"traceutil/trace.go:171","msg":"trace[1294797840] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:352; }","duration":"252.186759ms","start":"2026-03-06T00:28:32.781141Z","end":"2026-03-06T00:28:33.033328Z","steps":["trace[1294797840] 'read index received' (duration: 152.342443ms)","trace[1294797840] 'applied index is now lower than readState.Index' (duration: 99.843176ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:33.033487Z","caller":"traceutil/trace.go:171","msg":"trace[2580857] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"307.32963ms","start":"2026-03-06T00:28:32.726097Z","end":"2026-03-06T00:28:33.033427Z","steps":["trace[2580857] 'process raft request' (duration: 207.37276ms)","trace[2580857] 'compare' (duration: 99.717962ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:28:33.033597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.409356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:28:33.033702Z","caller":"traceutil/trace.go:171","msg":"trace[1745143968] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:338; }","duration":"252.558442ms","start":"2026-03-06T00:28:32.781116Z","end":"2026-03-06T00:28:33.033675Z","steps":["trace[1745143968] 'agreement among raft nodes before linearized reading' (duration: 252.314833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:33.033758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:32.72608Z","time spent":"307.528468ms","remote":"127.0.0.1:59456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:28:33.370655Z","caller":"traceutil/trace.go:171","msg":"trace[697050834] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"244.681887ms","start":"2026-03-06T00:28:33.125935Z","end":"2026-03-06T00:28:33.370617Z","steps":["trace[697050834] 'process raft request' (duration: 175.49959ms)","trace[697050834] 'compare' (duration: 68.98196ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:33.371124Z","caller":"traceutil/trace.go:171","msg":"trace[1155541426] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"240.312722ms","start":"2026-03-06T00:28:33.130799Z","end":"2026-03-06T00:28:33.371112Z","steps":["trace[1155541426] 'process raft request' (duration: 240.220679ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:33.830797Z","caller":"traceutil/trace.go:171","msg":"trace[1255889493] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:361; }","duration":"232.728176ms","start":"2026-03-06T00:28:33.598051Z","end":"2026-03-06T00:28:33.83078Z","steps":["trace[1255889493] 'read index received' (duration: 176.780006ms)","trace[1255889493] 'applied index is now lower than readState.Index' (duration: 55.94667ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:28:33.83105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.987057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-06T00:28:33.831302Z","caller":"traceutil/trace.go:171","msg":"trace[1915485783] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:348; }","duration":"233.282407ms","start":"2026-03-06T00:28:33.598002Z","end":"2026-03-06T00:28:33.831284Z","steps":["trace[1915485783] 'agreement among raft nodes before linearized reading' (duration: 232.958445ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:33.831137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.62235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-06T00:28:33.831551Z","caller":"traceutil/trace.go:171","msg":"trace[556794752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:348; }","duration":"195.967682ms","start":"2026-03-06T00:28:33.635465Z","end":"2026-03-06T00:28:33.831432Z","steps":["trace[556794752] 'agreement among raft nodes before linearized reading' (duration: 195.564358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:33.831579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.682296ms","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-03-06T00:28:33.831721Z","caller":"traceutil/trace.go:171","msg":"trace[65161332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:348; }","duration":"196.885594ms","start":"2026-03-06T00:28:33.634807Z","end":"2026-03-06T00:28:33.831693Z","steps":["trace[65161332] 'agreement among raft nodes before linearized reading' (duration: 196.217241ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:33.832271Z","caller":"traceutil/trace.go:171","msg":"trace[2126622055] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"247.946553ms","start":"2026-03-06T00:28:33.584257Z","end":"2026-03-06T00:28:33.832204Z","steps":["trace[2126622055] 'process raft request' (duration: 246.327445ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:33.830712Z","caller":"traceutil/trace.go:171","msg":"trace[230951505] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"251.87308ms","start":"2026-03-06T00:28:33.578792Z","end":"2026-03-06T00:28:33.830665Z","steps":["trace[230951505] 'process raft request' (duration: 196.106657ms)","trace[230951505] 'compare' (duration: 55.562746ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:28:34.081386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.03831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-06T00:28:34.081471Z","caller":"traceutil/trace.go:171","msg":"trace[1156918855] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:351; }","duration":"103.169545ms","start":"2026-03-06T00:28:33.978284Z","end":"2026-03-06T00:28:34.081453Z","steps":["trace[1156918855] 'range keys from in-memory index tree' (duration: 102.968638ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:34.389251Z","caller":"traceutil/trace.go:171","msg":"trace[177544516] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"183.571328ms","start":"2026-03-06T00:28:34.20565Z","end":"2026-03-06T00:28:34.389221Z","steps":["trace[177544516] 'process raft request' (duration: 169.923257ms)","trace[177544516] 'compare' (duration: 13.516027ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:34.456286Z","caller":"traceutil/trace.go:171","msg":"trace[1012711497] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:370; }","duration":"204.367479ms","start":"2026-03-06T00:28:34.251882Z","end":"2026-03-06T00:28:34.456249Z","steps":["trace[1012711497] 'read index received' (duration: 123.705498ms)","trace[1012711497] 'applied index is now lower than readState.Index' (duration: 80.660511ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:28:34.456751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.606673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-06T00:28:34.456798Z","caller":"traceutil/trace.go:171","msg":"trace[249145884] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:357; }","duration":"155.669595ms","start":"2026-03-06T00:28:34.301113Z","end":"2026-03-06T00:28:34.456783Z","steps":["trace[249145884] 'agreement among raft nodes before linearized reading' (duration: 155.602703ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:34.456402Z","caller":"traceutil/trace.go:171","msg":"trace[1251158384] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"246.697131ms","start":"2026-03-06T00:28:34.209658Z","end":"2026-03-06T00:28:34.456355Z","steps":["trace[1251158384] 'process raft request' (duration: 246.450363ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:34.457114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.03522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-03-06T00:28:34.456564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.65698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-06T00:28:34.457196Z","caller":"traceutil/trace.go:171","msg":"trace[1417089478] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:357; }","duration":"105.160484ms","start":"2026-03-06T00:28:34.352018Z","end":"2026-03-06T00:28:34.457178Z","steps":["trace[1417089478] 'agreement among raft nodes before linearized reading' (duration: 104.975627ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:34.457433Z","caller":"traceutil/trace.go:171","msg":"trace[221477886] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:357; }","duration":"205.338864ms","start":"2026-03-06T00:28:34.251862Z","end":"2026-03-06T00:28:34.457201Z","steps":["trace[221477886] 'agreement among raft nodes before linearized reading' (duration: 204.605718ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:34.570282Z","caller":"traceutil/trace.go:171","msg":"trace[666970425] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"105.89637ms","start":"2026-03-06T00:28:34.464344Z","end":"2026-03-06T00:28:34.57024Z","steps":["trace[666970425] 'process raft request' (duration: 31.554141ms)","trace[666970425] 'compare' (duration: 74.110461ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:34.57144Z","caller":"traceutil/trace.go:171","msg":"trace[75535316] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"104.1673ms","start":"2026-03-06T00:28:34.467224Z","end":"2026-03-06T00:28:34.571391Z","steps":["trace[75535316] 'process raft request' (duration: 103.705204ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:34.571458Z","caller":"traceutil/trace.go:171","msg":"trace[673440164] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"103.600201ms","start":"2026-03-06T00:28:34.467824Z","end":"2026-03-06T00:28:34.571424Z","steps":["trace[673440164] 'process raft request' (duration: 103.425214ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:34.571524Z","caller":"traceutil/trace.go:171","msg":"trace[25317341] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"103.414994ms","start":"2026-03-06T00:28:34.468082Z","end":"2026-03-06T00:28:34.571497Z","steps":["trace[25317341] 'process raft request' (duration: 103.222057ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:34.571827Z","caller":"traceutil/trace.go:171","msg":"trace[266747322] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"103.676483ms","start":"2026-03-06T00:28:34.468119Z","end":"2026-03-06T00:28:34.571795Z","steps":["trace[266747322] 'process raft request' (duration: 103.228607ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:34.572695Z","caller":"traceutil/trace.go:171","msg":"trace[313131901] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"102.282463ms","start":"2026-03-06T00:28:34.470389Z","end":"2026-03-06T00:28:34.572671Z","steps":["trace[313131901] 'process raft request' (duration: 101.828477ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:34.572885Z","caller":"traceutil/trace.go:171","msg":"trace[836886364] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"104.686479ms","start":"2026-03-06T00:28:34.468145Z","end":"2026-03-06T00:28:34.572832Z","steps":["trace[836886364] 'process raft request' (duration: 103.30335ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:34.572774Z","caller":"traceutil/trace.go:171","msg":"trace[1271256562] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"102.964557ms","start":"2026-03-06T00:28:34.469791Z","end":"2026-03-06T00:28:34.572756Z","steps":["trace[1271256562] 'process raft request' (duration: 102.363006ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:34.764865Z","caller":"traceutil/trace.go:171","msg":"trace[316818959] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:389; }","duration":"114.040388ms","start":"2026-03-06T00:28:34.650743Z","end":"2026-03-06T00:28:34.764783Z","steps":["trace[316818959] 'read index received' (duration: 22.338377ms)","trace[316818959] 'applied index is now lower than readState.Index' (duration: 91.700131ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:34.766182Z","caller":"traceutil/trace.go:171","msg":"trace[1797941360] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"121.819311ms","start":"2026-03-06T00:28:34.644328Z","end":"2026-03-06T00:28:34.766147Z","steps":["trace[1797941360] 'process raft request' (duration: 28.686661ms)","trace[1797941360] 'compare' (duration: 89.268435ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:28:34.766709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.940326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-06T00:28:34.767962Z","caller":"traceutil/trace.go:171","msg":"trace[1651866425] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"120.778915ms","start":"2026-03-06T00:28:34.646372Z","end":"2026-03-06T00:28:34.767151Z","steps":["trace[1651866425] 'process raft request' (duration: 117.912314ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:34.767551Z","caller":"traceutil/trace.go:171","msg":"trace[150135208] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:376; }","duration":"116.742584ms","start":"2026-03-06T00:28:34.650708Z","end":"2026-03-06T00:28:34.76745Z","steps":["trace[150135208] 'agreement among raft nodes before linearized reading' (duration: 115.892994ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:34.993218Z","caller":"traceutil/trace.go:171","msg":"trace[1296148674] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"217.196792ms","start":"2026-03-06T00:28:34.775987Z","end":"2026-03-06T00:28:34.993184Z","steps":["trace[1296148674] 'process raft request' (duration: 155.604752ms)","trace[1296148674] 'compare' (duration: 61.432544ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:34.994251Z","caller":"traceutil/trace.go:171","msg":"trace[873928638] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:391; }","duration":"122.816127ms","start":"2026-03-06T00:28:34.871418Z","end":"2026-03-06T00:28:34.994234Z","steps":["trace[873928638] 'read index received' (duration: 60.250462ms)","trace[873928638] 'applied index is now lower than readState.Index' (duration: 62.562615ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:34.99439Z","caller":"traceutil/trace.go:171","msg":"trace[1950180393] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"210.504026ms","start":"2026-03-06T00:28:34.783858Z","end":"2026-03-06T00:28:34.994362Z","steps":["trace[1950180393] 'process raft request' (duration: 210.272638ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:34.994434Z","caller":"traceutil/trace.go:171","msg":"trace[65849742] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"210.692023ms","start":"2026-03-06T00:28:34.783726Z","end":"2026-03-06T00:28:34.994418Z","steps":["trace[65849742] 'process raft request' (duration: 210.330971ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:34.994419Z","caller":"traceutil/trace.go:171","msg":"trace[2136829181] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"191.200796ms","start":"2026-03-06T00:28:34.803199Z","end":"2026-03-06T00:28:34.9944Z","steps":["trace[2136829181] 'process raft request' (duration: 190.973529ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:34.994909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.976683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-03-06T00:28:34.994962Z","caller":"traceutil/trace.go:171","msg":"trace[1939107631] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:380; }","duration":"123.603515ms","start":"2026-03-06T00:28:34.871346Z","end":"2026-03-06T00:28:34.99495Z","steps":["trace[1939107631] 'agreement among raft nodes before linearized reading' (duration: 122.952112ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:35.670591Z","caller":"traceutil/trace.go:171","msg":"trace[953194530] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"106.96312ms","start":"2026-03-06T00:28:35.563601Z","end":"2026-03-06T00:28:35.670564Z","steps":["trace[953194530] 'process raft request' (duration: 106.814155ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:36.025509Z","caller":"traceutil/trace.go:171","msg":"trace[82385006] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"136.388707ms","start":"2026-03-06T00:28:35.889099Z","end":"2026-03-06T00:28:36.025487Z","steps":["trace[82385006] 'process raft request' (duration: 136.18995ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:36.0257Z","caller":"traceutil/trace.go:171","msg":"trace[784288264] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"134.688767ms","start":"2026-03-06T00:28:35.890974Z","end":"2026-03-06T00:28:36.025663Z","steps":["trace[784288264] 'process raft request' (duration: 134.458599ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:36.65511Z","caller":"traceutil/trace.go:171","msg":"trace[972768672] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"150.689113ms","start":"2026-03-06T00:28:36.504389Z","end":"2026-03-06T00:28:36.655078Z","steps":["trace[972768672] 'process raft request' (duration: 150.497766ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:36.698022Z","caller":"traceutil/trace.go:171","msg":"trace[1675860352] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"131.674022ms","start":"2026-03-06T00:28:36.566314Z","end":"2026-03-06T00:28:36.697988Z","steps":["trace[1675860352] 'process raft request' (duration: 131.509196ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:41.926814Z","caller":"traceutil/trace.go:171","msg":"trace[1591832442] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"168.122457ms","start":"2026-03-06T00:28:41.758649Z","end":"2026-03-06T00:28:41.926771Z","steps":["trace[1591832442] 'process raft request' (duration: 68.892733ms)","trace[1591832442] 'compare' (duration: 98.879602ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:42.109985Z","caller":"traceutil/trace.go:171","msg":"trace[1824816446] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"101.126461ms","start":"2026-03-06T00:28:42.008813Z","end":"2026-03-06T00:28:42.109939Z","steps":["trace[1824816446] 'process raft request' (duration: 75.665652ms)","trace[1824816446] 'compare' (duration: 25.18041ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:28:58.853878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.579962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:28:58.854123Z","caller":"traceutil/trace.go:171","msg":"trace[752559153] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"163.22366ms","start":"2026-03-06T00:28:58.690878Z","end":"2026-03-06T00:28:58.854102Z","steps":["trace[752559153] 'process raft request' (duration: 163.167669ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:28:58.854203Z","caller":"traceutil/trace.go:171","msg":"trace[1439966318] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"164.629621ms","start":"2026-03-06T00:28:58.689484Z","end":"2026-03-06T00:28:58.854114Z","steps":["trace[1439966318] 'process raft request' (duration: 54.723547ms)","trace[1439966318] 'compare' (duration: 109.37954ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:28:59.564776Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724724849786186,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-06T00:28:59.801334Z","caller":"traceutil/trace.go:171","msg":"trace[1437369743] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"748.588088ms","start":"2026-03-06T00:28:59.052696Z","end":"2026-03-06T00:28:59.801284Z","steps":["trace[1437369743] 'read index received' (duration: 730.171661ms)","trace[1437369743] 'applied index is now lower than readState.Index' (duration: 18.414137ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:28:59.801358Z","caller":"traceutil/trace.go:171","msg":"trace[1490949595] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"941.108224ms","start":"2026-03-06T00:28:58.860223Z","end":"2026-03-06T00:28:59.801331Z","steps":["trace[1490949595] 'process raft request' (duration: 922.780418ms)","trace[1490949595] 'compare' (duration: 17.927213ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:28:59.801552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:58.86019Z","time spent":"941.266696ms","remote":"127.0.0.1:59382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6516,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T00:28:59.801599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.87672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-06T00:28:59.801695Z","caller":"traceutil/trace.go:171","msg":"trace[1191905011] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:497; }","duration":"749.023511ms","start":"2026-03-06T00:28:59.052651Z","end":"2026-03-06T00:28:59.801675Z","steps":["trace[1191905011] 'agreement among raft nodes before linearized reading' (duration: 748.774359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:28:59.801746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:28:59.052631Z","time spent":"749.106872ms","remote":"127.0.0.1:59292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"info","ts":"2026-03-06T00:29:02.133397Z","caller":"traceutil/trace.go:171","msg":"trace[517097237] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"797.868715ms","start":"2026-03-06T00:29:01.335511Z","end":"2026-03-06T00:29:02.13338Z","steps":["trace[517097237] 'process raft request' (duration: 797.759434ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:29:02.133455Z","caller":"traceutil/trace.go:171","msg":"trace[109832010] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:554; }","duration":"229.150409ms","start":"2026-03-06T00:29:01.904282Z","end":"2026-03-06T00:29:02.133433Z","steps":["trace[109832010] 'read index received' (duration: 229.142419ms)","trace[109832010] 'applied index is now lower than readState.Index' (duration: 6.13µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:29:02.13354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:29:01.33548Z","time spent":"797.984256ms","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:29:02.133613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:29:02.133645Z","caller":"traceutil/trace.go:171","msg":"trace[1700864005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"229.409551ms","start":"2026-03-06T00:29:01.904223Z","end":"2026-03-06T00:29:02.133632Z","steps":["trace[1700864005] 'agreement among raft nodes before linearized reading' (duration: 229.316471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:29:02.136004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.063107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-06T00:29:02.136094Z","caller":"traceutil/trace.go:171","msg":"trace[423479846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:534; }","duration":"108.158798ms","start":"2026-03-06T00:29:02.027914Z","end":"2026-03-06T00:29:02.136072Z","steps":["trace[423479846] 'agreement among raft nodes before linearized reading' (duration: 108.009596ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:29:08.487924Z","caller":"traceutil/trace.go:171","msg":"trace[1435617020] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"309.709877ms","start":"2026-03-06T00:29:08.178184Z","end":"2026-03-06T00:29:08.487894Z","steps":["trace[1435617020] 'process raft request' (duration: 309.528135ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:29:08.488128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:29:08.17817Z","time spent":"309.850939ms","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:29:08.870712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.828994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:29:08.870803Z","caller":"traceutil/trace.go:171","msg":"trace[896807622] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:594; }","duration":"631.787586ms","start":"2026-03-06T00:29:08.239001Z","end":"2026-03-06T00:29:08.870789Z","steps":["trace[896807622] 'read index received' (duration: 248.807645ms)","trace[896807622] 'applied index is now lower than readState.Index' (duration: 382.978731ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:29:08.870901Z","caller":"traceutil/trace.go:171","msg":"trace[675712430] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"682.969804ms","start":"2026-03-06T00:29:08.187894Z","end":"2026-03-06T00:29:08.870864Z","steps":["trace[675712430] 'process raft request' (duration: 421.908528ms)","trace[675712430] 'compare' (duration: 260.742013ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:29:08.870966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.973018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-06T00:29:08.871007Z","caller":"traceutil/trace.go:171","msg":"trace[941939429] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"632.046688ms","start":"2026-03-06T00:29:08.238954Z","end":"2026-03-06T00:29:08.871001Z","steps":["trace[941939429] 'agreement among raft nodes before linearized reading' (duration: 631.907717ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:29:08.871031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:29:08.238931Z","time spent":"632.094989ms","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-06T00:29:08.871052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.82493ms","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-03-06T00:29:08.871092Z","caller":"traceutil/trace.go:171","msg":"trace[1782554083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:574; }","duration":"622.937971ms","start":"2026-03-06T00:29:08.248143Z","end":"2026-03-06T00:29:08.871081Z","steps":["trace[1782554083] 'agreement among raft nodes before linearized reading' (duration: 622.76612ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:29:08.871115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:29:08.248125Z","time spent":"622.984922ms","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T00:29:08.871107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:29:08.187848Z","time spent":"683.158886ms","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:29:08.871419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.485931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n4nz5\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-06T00:29:08.871534Z","caller":"traceutil/trace.go:171","msg":"trace[27987481] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n4nz5; range_end:; response_count:1; response_revision:574; }","duration":"249.687764ms","start":"2026-03-06T00:29:08.621817Z","end":"2026-03-06T00:29:08.871504Z","steps":["trace[27987481] 'agreement among raft nodes before linearized reading' (duration: 249.248339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:29:09.256979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.547105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:29:09.257077Z","caller":"traceutil/trace.go:171","msg":"trace[1068584846] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"376.551038ms","start":"2026-03-06T00:29:08.880512Z","end":"2026-03-06T00:29:09.257063Z","steps":["trace[1068584846] 'read index received' (duration: 166.001252ms)","trace[1068584846] 'applied index is now lower than readState.Index' (duration: 210.548486ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:29:09.257134Z","caller":"traceutil/trace.go:171","msg":"trace[1420877989] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"382.982827ms","start":"2026-03-06T00:29:08.874144Z","end":"2026-03-06T00:29:09.257127Z","steps":["trace[1420877989] 'process raft request' (duration: 172.212358ms)","trace[1420877989] 'compare' (duration: 210.421954ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:29:09.257176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:29:08.874119Z","time spent":"383.037887ms","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:29:09.25725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.71035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:29:09.257374Z","caller":"traceutil/trace.go:171","msg":"trace[1694814395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"376.866801ms","start":"2026-03-06T00:29:08.880481Z","end":"2026-03-06T00:29:09.257347Z","steps":["trace[1694814395] 'agreement among raft nodes before linearized reading' (duration: 376.654699ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:29:09.257429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:29:08.880462Z","time spent":"376.956612ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:29:09.757872Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724724849786338,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-06T00:29:09.845023Z","caller":"traceutil/trace.go:171","msg":"trace[1088351089] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"963.916095ms","start":"2026-03-06T00:29:08.881076Z","end":"2026-03-06T00:29:09.844992Z","steps":["trace[1088351089] 'process raft request' (duration: 963.655202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:29:09.845197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:29:08.88106Z","time spent":"964.034136ms","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:29:09.845216Z","caller":"traceutil/trace.go:171","msg":"trace[950255459] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:597; }","duration":"588.085485ms","start":"2026-03-06T00:29:09.257095Z","end":"2026-03-06T00:29:09.845181Z","steps":["trace[950255459] 'read index received' (duration: 587.61231ms)","trace[950255459] 'applied index is now lower than readState.Index' (duration: 470.795µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:29:09.845423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.337511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-06T00:29:09.845459Z","caller":"traceutil/trace.go:171","msg":"trace[1159318464] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:577; }","duration":"792.463752ms","start":"2026-03-06T00:29:09.052984Z","end":"2026-03-06T00:29:09.845448Z","steps":["trace[1159318464] 'agreement among raft nodes before linearized reading' (duration: 792.32551ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:29:09.845451Z","caller":"traceutil/trace.go:171","msg":"trace[791729946] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"960.189585ms","start":"2026-03-06T00:29:08.885208Z","end":"2026-03-06T00:29:09.845398Z","steps":["trace[791729946] 'process raft request' (duration: 959.712089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:29:09.84551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:29:09.052958Z","time spent":"792.539493ms","remote":"127.0.0.1:59292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-03-06T00:29:09.845695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:29:08.885199Z","time spent":"960.351897ms","remote":"127.0.0.1:59456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:29:11.841638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.160621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:29:11.841715Z","caller":"traceutil/trace.go:171","msg":"trace[1586686378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"973.323293ms","start":"2026-03-06T00:29:10.868373Z","end":"2026-03-06T00:29:11.841696Z","steps":["trace[1586686378] 'range keys from in-memory index tree' (duration: 973.005669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:29:11.841744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:29:10.86835Z","time spent":"973.388254ms","remote":"127.0.0.1:59248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:29:11.841862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.588605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-03-06T00:29:11.841882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34938427s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-06T00:29:11.841926Z","caller":"traceutil/trace.go:171","msg":"trace[944761235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"965.649756ms","start":"2026-03-06T00:29:10.876256Z","end":"2026-03-06T00:29:11.841906Z","steps":["trace[944761235] 'range keys from in-memory index tree' (duration: 965.335572ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:29:11.841979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:29:10.876173Z","time spent":"965.795988ms","remote":"127.0.0.1:59530","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":"info","ts":"2026-03-06T00:29:11.841985Z","caller":"traceutil/trace.go:171","msg":"trace[1937580419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"1.349596463s","start":"2026-03-06T00:29:10.492364Z","end":"2026-03-06T00:29:11.84196Z","steps":["trace[1937580419] 'range keys from in-memory index tree' (duration: 1.349174148s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:29:11.842304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:29:10.492338Z","time spent":"1.349686565s","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-06T00:29:11.84243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.590514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-06T00:29:11.84263Z","caller":"traceutil/trace.go:171","msg":"trace[1922404057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"581.821096ms","start":"2026-03-06T00:29:11.260739Z","end":"2026-03-06T00:29:11.84256Z","steps":["trace[1922404057] 'range keys from in-memory index tree' (duration: 580.547412ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:29:11.842717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:29:11.260717Z","time spent":"581.98879ms","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-06T00:29:13.929171Z","caller":"traceutil/trace.go:171","msg":"trace[1676171412] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"135.724163ms","start":"2026-03-06T00:29:13.793402Z","end":"2026-03-06T00:29:13.929126Z","steps":["trace[1676171412] 'process raft request' (duration: 135.533071ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:30:48.24107Z","caller":"traceutil/trace.go:171","msg":"trace[2122052695] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"207.956518ms","start":"2026-03-06T00:30:48.033069Z","end":"2026-03-06T00:30:48.241026Z","steps":["trace[2122052695] 'process raft request' (duration: 207.697751ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:30:48.785241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.024374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:30:48.785365Z","caller":"traceutil/trace.go:171","msg":"trace[821142586] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"127.663561ms","start":"2026-03-06T00:30:48.657683Z","end":"2026-03-06T00:30:48.785346Z","steps":["trace[821142586] 'process raft request' (duration: 18.435401ms)","trace[821142586] 'compare' (duration: 108.86766ms)"],"step_count":2}