{"level":"warn","ts":"2026-03-25T07:34:20.142365Z","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-25T07:34:20.142456Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.221:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.221:2380","--initial-cluster=instance=https://199.19.213.221:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.221:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.221: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-25T07:34:20.142537Z","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-25T07:34:20.142552Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.221:2380"]} {"level":"info","ts":"2026-03-25T07:34:20.14257Z","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-25T07:34:20.143204Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.221:2379"]} {"level":"info","ts":"2026-03-25T07:34:20.14328Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.221:2380"],"listen-peer-urls":["https://199.19.213.221:2380"],"advertise-client-urls":["https://199.19.213.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.221: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-25T07:34:20.151248Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.743293ms"} {"level":"info","ts":"2026-03-25T07:34:20.1744Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b506daf99e68981a","cluster-id":"d2c45690f9793857"} {"level":"info","ts":"2026-03-25T07:34:20.174498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a switched to configuration voters=()"} {"level":"info","ts":"2026-03-25T07:34:20.174523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a became follower at term 0"} {"level":"info","ts":"2026-03-25T07:34:20.174537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b506daf99e68981a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-25T07:34:20.174548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a became follower at term 1"} {"level":"info","ts":"2026-03-25T07:34:20.174589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a switched to configuration voters=(13044354136364587034)"} {"level":"warn","ts":"2026-03-25T07:34:20.191929Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-25T07:34:20.193536Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-25T07:34:20.199109Z","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-25T07:34:20.2023Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b506daf99e68981a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-25T07:34:20.202781Z","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-25T07:34:20.202825Z","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-25T07:34:20.202839Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-25T07:34:20.203101Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b506daf99e68981a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-25T07:34:20.203519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a switched to configuration voters=(13044354136364587034)"} {"level":"info","ts":"2026-03-25T07:34:20.204344Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d2c45690f9793857","local-member-id":"b506daf99e68981a","added-peer-id":"b506daf99e68981a","added-peer-peer-urls":["https://199.19.213.221:2380"]} {"level":"info","ts":"2026-03-25T07:34:20.208579Z","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-25T07:34:20.20889Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b506daf99e68981a","initial-advertise-peer-urls":["https://199.19.213.221:2380"],"listen-peer-urls":["https://199.19.213.221:2380"],"advertise-client-urls":["https://199.19.213.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-25T07:34:20.208914Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-25T07:34:20.209002Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.221:2380"} {"level":"info","ts":"2026-03-25T07:34:20.209014Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.221:2380"} {"level":"info","ts":"2026-03-25T07:34:20.775765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a is starting a new election at term 1"} {"level":"info","ts":"2026-03-25T07:34:20.775825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a became pre-candidate at term 1"} {"level":"info","ts":"2026-03-25T07:34:20.775862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a received MsgPreVoteResp from b506daf99e68981a at term 1"} {"level":"info","ts":"2026-03-25T07:34:20.775893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a became candidate at term 2"} {"level":"info","ts":"2026-03-25T07:34:20.77591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a received MsgVoteResp from b506daf99e68981a at term 2"} {"level":"info","ts":"2026-03-25T07:34:20.775926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b506daf99e68981a became leader at term 2"} {"level":"info","ts":"2026-03-25T07:34:20.775945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b506daf99e68981a elected leader b506daf99e68981a at term 2"} {"level":"info","ts":"2026-03-25T07:34:20.777079Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b506daf99e68981a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.221:2379]}","request-path":"/0/members/b506daf99e68981a/attributes","cluster-id":"d2c45690f9793857","publish-timeout":"7s"} {"level":"info","ts":"2026-03-25T07:34:20.777237Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T07:34:20.777754Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T07:34:20.777855Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T07:34:20.778064Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-25T07:34:20.778124Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-25T07:34:20.778702Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d2c45690f9793857","local-member-id":"b506daf99e68981a","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T07:34:20.778757Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T07:34:20.778776Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T07:34:20.780334Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.221:2379"} {"level":"info","ts":"2026-03-25T07:34:20.78109Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-25T07:34:45.395618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960245420742218225,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:34:45.743755Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.141960366s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T07:34:45.744306Z","caller":"traceutil/trace.go:171","msg":"trace[531518959] linearizableReadLoop","detail":"{readStateIndex:473; appliedIndex:472; }","duration":"848.956111ms","start":"2026-03-25T07:34:44.895317Z","end":"2026-03-25T07:34:45.744273Z","steps":["trace[531518959] 'read index received' (duration: 848.57858ms)","trace[531518959] 'applied index is now lower than readState.Index' (duration: 368.68µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:45.744475Z","caller":"traceutil/trace.go:171","msg":"trace[111875228] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"1.142715856s","start":"2026-03-25T07:34:44.601702Z","end":"2026-03-25T07:34:45.744418Z","steps":["trace[111875228] 'process raft request' (duration: 1.142352267s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:45.744549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.851851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:34:45.744652Z","caller":"traceutil/trace.go:171","msg":"trace[2083855391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:461; }","duration":"635.021586ms","start":"2026-03-25T07:34:45.109618Z","end":"2026-03-25T07:34:45.744639Z","steps":["trace[2083855391] 'agreement among raft nodes before linearized reading' (duration: 634.853322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:45.744666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.312949ms","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-03-25T07:34:45.744693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:45.109599Z","time spent":"635.085217ms","remote":"127.0.0.1:48796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-25T07:34:45.74478Z","caller":"traceutil/trace.go:171","msg":"trace[934996599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:461; }","duration":"849.472993ms","start":"2026-03-25T07:34:44.895278Z","end":"2026-03-25T07:34:45.744751Z","steps":["trace[934996599] 'agreement among raft nodes before linearized reading' (duration: 849.175806ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:45.744834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:44.895259Z","time spent":"849.565865ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-25T07:34:45.745131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:44.601687Z","time spent":"1.14288691s","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:34:46.473932Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960245420742218229,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:34:46.697803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.632578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:34:46.698119Z","caller":"traceutil/trace.go:171","msg":"trace[1381674072] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"948.365709ms","start":"2026-03-25T07:34:45.749707Z","end":"2026-03-25T07:34:46.698073Z","steps":["trace[1381674072] 'process raft request' (duration: 812.421619ms)","trace[1381674072] 'compare' (duration: 134.429603ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:46.69828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:45.749687Z","time spent":"948.522402ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:34:46.701911Z","caller":"traceutil/trace.go:171","msg":"trace[607664136] linearizableReadLoop","detail":"{readStateIndex:475; appliedIndex:473; }","duration":"728.465556ms","start":"2026-03-25T07:34:45.973391Z","end":"2026-03-25T07:34:46.701856Z","steps":["trace[607664136] 'read index received' (duration: 588.809554ms)","trace[607664136] 'applied index is now lower than readState.Index' (duration: 139.654752ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:46.701938Z","caller":"traceutil/trace.go:171","msg":"trace[1299603693] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"456.344799ms","start":"2026-03-25T07:34:46.245544Z","end":"2026-03-25T07:34:46.701889Z","steps":["trace[1299603693] 'process raft request' (duration: 452.392201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:46.702136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.600283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T07:34:46.70215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.127735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-25T07:34:46.702188Z","caller":"traceutil/trace.go:171","msg":"trace[1383998673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:463; }","duration":"591.721177ms","start":"2026-03-25T07:34:46.110453Z","end":"2026-03-25T07:34:46.702174Z","steps":["trace[1383998673] 'agreement among raft nodes before linearized reading' (duration: 591.631115ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:46.702216Z","caller":"traceutil/trace.go:171","msg":"trace[1640178731] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:463; }","duration":"545.226967ms","start":"2026-03-25T07:34:46.156963Z","end":"2026-03-25T07:34:46.70219Z","steps":["trace[1640178731] 'agreement among raft nodes before linearized reading' (duration: 545.124785ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:46.702226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:46.110404Z","time spent":"591.807039ms","remote":"127.0.0.1:48796","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-25T07:34:46.70228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:46.156934Z","time spent":"545.335659ms","remote":"127.0.0.1:49058","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-03-25T07:34:46.702275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:46.245516Z","time spent":"456.612707ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:34:46.702343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.973599ms","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-25T07:34:46.702434Z","caller":"traceutil/trace.go:171","msg":"trace[2018693498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:463; }","duration":"729.101142ms","start":"2026-03-25T07:34:45.973295Z","end":"2026-03-25T07:34:46.702397Z","steps":["trace[2018693498] 'agreement among raft nodes before linearized reading' (duration: 728.689451ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:46.703861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:45.973269Z","time spent":"729.437951ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-25T07:34:48.018845Z","caller":"traceutil/trace.go:171","msg":"trace[1557688165] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:477; }","duration":"137.722165ms","start":"2026-03-25T07:34:47.881104Z","end":"2026-03-25T07:34:48.018826Z","steps":["trace[1557688165] 'read index received' (duration: 137.573841ms)","trace[1557688165] 'applied index is now lower than readState.Index' (duration: 147.594µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:48.018942Z","caller":"traceutil/trace.go:171","msg":"trace[793057393] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"265.758147ms","start":"2026-03-25T07:34:47.753157Z","end":"2026-03-25T07:34:48.018915Z","steps":["trace[793057393] 'process raft request' (duration: 265.577003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:48.018971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.852919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:34:48.019096Z","caller":"traceutil/trace.go:171","msg":"trace[1076925831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:466; }","duration":"138.031782ms","start":"2026-03-25T07:34:47.881052Z","end":"2026-03-25T07:34:48.019084Z","steps":["trace[1076925831] 'agreement among raft nodes before linearized reading' (duration: 137.846628ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:59.01614Z","caller":"traceutil/trace.go:171","msg":"trace[312150871] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"214.993091ms","start":"2026-03-25T07:34:58.801119Z","end":"2026-03-25T07:34:59.016113Z","steps":["trace[312150871] 'process raft request' (duration: 158.279807ms)","trace[312150871] 'compare' (duration: 56.53545ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:59.016275Z","caller":"traceutil/trace.go:171","msg":"trace[697586548] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"211.839811ms","start":"2026-03-25T07:34:58.804413Z","end":"2026-03-25T07:34:59.016253Z","steps":["trace[697586548] 'process raft request' (duration: 211.660017ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:59.363816Z","caller":"traceutil/trace.go:171","msg":"trace[1909601004] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"316.02009ms","start":"2026-03-25T07:34:59.047743Z","end":"2026-03-25T07:34:59.363763Z","steps":["trace[1909601004] 'process raft request' (duration: 271.85204ms)","trace[1909601004] 'compare' (duration: 43.899173ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:59.363958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.528686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:34:59.364102Z","caller":"traceutil/trace.go:171","msg":"trace[632957443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"254.669191ms","start":"2026-03-25T07:34:59.109365Z","end":"2026-03-25T07:34:59.364034Z","steps":["trace[632957443] 'agreement among raft nodes before linearized reading' (duration: 254.475006ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:59.363658Z","caller":"traceutil/trace.go:171","msg":"trace[711509103] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"254.215418ms","start":"2026-03-25T07:34:59.109414Z","end":"2026-03-25T07:34:59.36363Z","steps":["trace[711509103] 'read index received' (duration: 210.201401ms)","trace[711509103] 'applied index is now lower than readState.Index' (duration: 44.013057ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:59.364329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:59.047671Z","time spent":"316.269096ms","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:35:00.947067Z","caller":"traceutil/trace.go:171","msg":"trace[149429318] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"100.790133ms","start":"2026-03-25T07:35:00.846247Z","end":"2026-03-25T07:35:00.947037Z","steps":["trace[149429318] 'read index received' (duration: 100.452815ms)","trace[149429318] 'applied index is now lower than readState.Index' (duration: 336.458µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:35:00.947137Z","caller":"traceutil/trace.go:171","msg":"trace[1137082908] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"787.366886ms","start":"2026-03-25T07:35:00.159747Z","end":"2026-03-25T07:35:00.947114Z","steps":["trace[1137082908] 'process raft request' (duration: 787.118819ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:00.94736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:00.159727Z","time spent":"787.472828ms","remote":"127.0.0.1:49058","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-25T07:35:00.947337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.051581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-25T07:35:00.947575Z","caller":"traceutil/trace.go:171","msg":"trace[1116254003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"101.307517ms","start":"2026-03-25T07:35:00.846242Z","end":"2026-03-25T07:35:00.94755Z","steps":["trace[1116254003] 'agreement among raft nodes before linearized reading' (duration: 100.903707ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:21.060596Z","caller":"traceutil/trace.go:171","msg":"trace[985275545] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"134.104538ms","start":"2026-03-25T07:35:20.926454Z","end":"2026-03-25T07:35:21.060558Z","steps":["trace[985275545] 'process raft request' (duration: 133.943304ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:21.200081Z","caller":"traceutil/trace.go:171","msg":"trace[1446580919] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"135.483332ms","start":"2026-03-25T07:35:21.064565Z","end":"2026-03-25T07:35:21.200049Z","steps":["trace[1446580919] 'process raft request' (duration: 135.181514ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:21.204384Z","caller":"traceutil/trace.go:171","msg":"trace[2135952422] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"139.39987ms","start":"2026-03-25T07:35:21.064947Z","end":"2026-03-25T07:35:21.204347Z","steps":["trace[2135952422] 'process raft request' (duration: 139.104073ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:44.144448Z","caller":"traceutil/trace.go:171","msg":"trace[1380065201] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"205.752487ms","start":"2026-03-25T07:35:43.938664Z","end":"2026-03-25T07:35:44.144417Z","steps":["trace[1380065201] 'process raft request' (duration: 205.410518ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:45.688803Z","caller":"traceutil/trace.go:171","msg":"trace[2144150789] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"373.92695ms","start":"2026-03-25T07:35:45.314848Z","end":"2026-03-25T07:35:45.688775Z","steps":["trace[2144150789] 'process raft request' (duration: 373.779326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:45.688947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:45.314825Z","time spent":"374.050073ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:35:45.689107Z","caller":"traceutil/trace.go:171","msg":"trace[834134875] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"312.532909ms","start":"2026-03-25T07:35:45.376556Z","end":"2026-03-25T07:35:45.689089Z","steps":["trace[834134875] 'read index received' (duration: 311.995635ms)","trace[834134875] 'applied index is now lower than readState.Index' (duration: 535.924µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:45.689289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.734693ms","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-25T07:35:45.689329Z","caller":"traceutil/trace.go:171","msg":"trace[1549191207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:797; }","duration":"312.811315ms","start":"2026-03-25T07:35:45.376507Z","end":"2026-03-25T07:35:45.689319Z","steps":["trace[1549191207] 'agreement among raft nodes before linearized reading' (duration: 312.678312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:45.689362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:45.376485Z","time spent":"312.870326ms","remote":"127.0.0.1:49058","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-25T07:35:45.689363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.878599ms","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-25T07:35:45.689401Z","caller":"traceutil/trace.go:171","msg":"trace[40466633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:797; }","duration":"297.937031ms","start":"2026-03-25T07:35:45.391453Z","end":"2026-03-25T07:35:45.68939Z","steps":["trace[40466633] 'agreement among raft nodes before linearized reading' (duration: 297.838649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:45.690148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.543304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-25T07:35:45.690243Z","caller":"traceutil/trace.go:171","msg":"trace[122955937] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:797; }","duration":"313.690907ms","start":"2026-03-25T07:35:45.376535Z","end":"2026-03-25T07:35:45.690226Z","steps":["trace[122955937] 'agreement among raft nodes before linearized reading' (duration: 312.699752ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:45.690288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:45.376521Z","time spent":"313.758319ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-25T07:35:58.029316Z","caller":"traceutil/trace.go:171","msg":"trace[1246642382] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:910; }","duration":"294.201598ms","start":"2026-03-25T07:35:57.735094Z","end":"2026-03-25T07:35:58.029296Z","steps":["trace[1246642382] 'read index received' (duration: 294.039284ms)","trace[1246642382] 'applied index is now lower than readState.Index' (duration: 161.544µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:58.029635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.372995ms","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-25T07:35:58.029668Z","caller":"traceutil/trace.go:171","msg":"trace[1675119744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:881; }","duration":"275.454786ms","start":"2026-03-25T07:35:57.754203Z","end":"2026-03-25T07:35:58.029658Z","steps":["trace[1675119744] 'agreement among raft nodes before linearized reading' (duration: 275.322923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:58.029648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.525786ms","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-25T07:35:58.029729Z","caller":"traceutil/trace.go:171","msg":"trace[44577784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:881; }","duration":"294.654089ms","start":"2026-03-25T07:35:57.735053Z","end":"2026-03-25T07:35:58.029708Z","steps":["trace[44577784] 'agreement among raft nodes before linearized reading' (duration: 294.403543ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:58.029718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.43799ms","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-03-25T07:35:58.02984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.932267ms","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-25T07:35:58.02982Z","caller":"traceutil/trace.go:171","msg":"trace[1845352004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:881; }","duration":"264.574143ms","start":"2026-03-25T07:35:57.765219Z","end":"2026-03-25T07:35:58.029793Z","steps":["trace[1845352004] 'agreement among raft nodes before linearized reading' (duration: 264.309847ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:58.029862Z","caller":"traceutil/trace.go:171","msg":"trace[1789785228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:881; }","duration":"265.977159ms","start":"2026-03-25T07:35:57.763878Z","end":"2026-03-25T07:35:58.029856Z","steps":["trace[1789785228] 'agreement among raft nodes before linearized reading' (duration: 265.916317ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:58.029928Z","caller":"traceutil/trace.go:171","msg":"trace[1710312003] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"701.328801ms","start":"2026-03-25T07:35:57.328594Z","end":"2026-03-25T07:35:58.029922Z","steps":["trace[1710312003] 'process raft request' (duration: 700.597022ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:58.029992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:57.328572Z","time spent":"701.369692ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:36:02.21147Z","caller":"traceutil/trace.go:171","msg":"trace[548235839] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"155.210399ms","start":"2026-03-25T07:36:02.056242Z","end":"2026-03-25T07:36:02.211452Z","steps":["trace[548235839] 'process raft request' (duration: 154.978933ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:36:02.211454Z","caller":"traceutil/trace.go:171","msg":"trace[1675943488] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"155.147227ms","start":"2026-03-25T07:36:02.05628Z","end":"2026-03-25T07:36:02.211428Z","steps":["trace[1675943488] 'process raft request' (duration: 154.991853ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:36:02.211647Z","caller":"traceutil/trace.go:171","msg":"trace[1816397510] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"155.440414ms","start":"2026-03-25T07:36:02.0562Z","end":"2026-03-25T07:36:02.21164Z","steps":["trace[1816397510] 'process raft request' (duration: 154.85692ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:36:02.211703Z","caller":"traceutil/trace.go:171","msg":"trace[296623837] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:922; }","duration":"103.254333ms","start":"2026-03-25T07:36:02.108422Z","end":"2026-03-25T07:36:02.211676Z","steps":["trace[296623837] 'read index received' (duration: 13.417107ms)","trace[296623837] 'applied index is now lower than readState.Index' (duration: 89.835546ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:36:02.211724Z","caller":"traceutil/trace.go:171","msg":"trace[1776083205] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"153.465614ms","start":"2026-03-25T07:36:02.058249Z","end":"2026-03-25T07:36:02.211715Z","steps":["trace[1776083205] 'process raft request' (duration: 153.110175ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:02.211847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.389166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:36:02.21187Z","caller":"traceutil/trace.go:171","msg":"trace[1542992676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"103.470549ms","start":"2026-03-25T07:36:02.108393Z","end":"2026-03-25T07:36:02.211863Z","steps":["trace[1542992676] 'agreement among raft nodes before linearized reading' (duration: 103.383217ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:36:16.420294Z","caller":"traceutil/trace.go:171","msg":"trace[552724026] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"139.735219ms","start":"2026-03-25T07:36:16.280541Z","end":"2026-03-25T07:36:16.420276Z","steps":["trace[552724026] 'read index received' (duration: 139.624666ms)","trace[552724026] 'applied index is now lower than readState.Index' (duration: 109.723µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:36:16.420375Z","caller":"traceutil/trace.go:171","msg":"trace[1194209588] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"236.881679ms","start":"2026-03-25T07:36:16.183461Z","end":"2026-03-25T07:36:16.420343Z","steps":["trace[1194209588] 'process raft request' (duration: 236.711855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:16.420467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.896043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-25T07:36:16.420492Z","caller":"traceutil/trace.go:171","msg":"trace[1126324926] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:953; }","duration":"139.972775ms","start":"2026-03-25T07:36:16.280512Z","end":"2026-03-25T07:36:16.420484Z","steps":["trace[1126324926] 'agreement among raft nodes before linearized reading' (duration: 139.859802ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:16.782502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.345231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:36:16.782582Z","caller":"traceutil/trace.go:171","msg":"trace[655085659] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"357.604639ms","start":"2026-03-25T07:36:16.424964Z","end":"2026-03-25T07:36:16.782569Z","steps":["trace[655085659] 'process raft request' (duration: 114.129515ms)","trace[655085659] 'compare' (duration: 243.229298ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:36:16.782619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:16.424946Z","time spent":"357.65582ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:36:16.78502Z","caller":"traceutil/trace.go:171","msg":"trace[1219158702] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"359.005815ms","start":"2026-03-25T07:36:16.426004Z","end":"2026-03-25T07:36:16.78501Z","steps":["trace[1219158702] 'process raft request' (duration: 358.984974ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:16.78508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:16.425987Z","time spent":"359.064457ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:36:16.785075Z","caller":"traceutil/trace.go:171","msg":"trace[159732734] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"359.130038ms","start":"2026-03-25T07:36:16.42592Z","end":"2026-03-25T07:36:16.78505Z","steps":["trace[159732734] 'process raft request' (duration: 358.999244ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:36:16.785187Z","caller":"traceutil/trace.go:171","msg":"trace[1354107975] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"359.181189ms","start":"2026-03-25T07:36:16.426Z","end":"2026-03-25T07:36:16.785181Z","steps":["trace[1354107975] 'process raft request' (duration: 358.968364ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:16.785194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:16.425899Z","time spent":"359.229831ms","remote":"127.0.0.1:49058","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-25T07:36:16.785218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:16.42599Z","time spent":"359.21292ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:36:17.120899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.606621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:36:17.121137Z","caller":"traceutil/trace.go:171","msg":"trace[1667303496] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"239.179476ms","start":"2026-03-25T07:36:16.88191Z","end":"2026-03-25T07:36:17.12109Z","steps":["trace[1667303496] 'process raft request' (duration: 94.313098ms)","trace[1667303496] 'compare' (duration: 144.487769ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:36:17.121307Z","caller":"traceutil/trace.go:171","msg":"trace[1971193838] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"160.797317ms","start":"2026-03-25T07:36:16.960481Z","end":"2026-03-25T07:36:17.121278Z","steps":["trace[1971193838] 'process raft request' (duration: 160.524141ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:17.380033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.535293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:36:17.380213Z","caller":"traceutil/trace.go:171","msg":"trace[46507639] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"252.477411ms","start":"2026-03-25T07:36:17.127722Z","end":"2026-03-25T07:36:17.380199Z","steps":["trace[46507639] 'process raft request' (duration: 252.390869ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:36:17.380444Z","caller":"traceutil/trace.go:171","msg":"trace[1773929068] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"253.093756ms","start":"2026-03-25T07:36:17.127321Z","end":"2026-03-25T07:36:17.380415Z","steps":["trace[1773929068] 'process raft request' (duration: 119.117502ms)","trace[1773929068] 'compare' (duration: 133.383548ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:36:28.919055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10960245420742219478,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-25T07:36:28.941074Z","caller":"traceutil/trace.go:171","msg":"trace[690925126] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1067; }","duration":"522.501963ms","start":"2026-03-25T07:36:28.418554Z","end":"2026-03-25T07:36:28.941056Z","steps":["trace[690925126] 'read index received' (duration: 522.287628ms)","trace[690925126] 'applied index is now lower than readState.Index' (duration: 213.595µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:36:28.941279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.70278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:36:28.941332Z","caller":"traceutil/trace.go:171","msg":"trace[425792591] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1032; }","duration":"522.808212ms","start":"2026-03-25T07:36:28.418515Z","end":"2026-03-25T07:36:28.941323Z","steps":["trace[425792591] 'agreement among raft nodes before linearized reading' (duration: 522.693458ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:36:28.941338Z","caller":"traceutil/trace.go:171","msg":"trace[112856976] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"594.984652ms","start":"2026-03-25T07:36:28.346325Z","end":"2026-03-25T07:36:28.94131Z","steps":["trace[112856976] 'process raft request' (duration: 594.613622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:28.941375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:28.418501Z","time spent":"522.864954ms","remote":"127.0.0.1:49108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-25T07:36:28.941471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:28.346307Z","time spent":"595.094354ms","remote":"127.0.0.1:48970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6611,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:36:28.941637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.789478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-25T07:36:28.94172Z","caller":"traceutil/trace.go:171","msg":"trace[1328316728] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1032; }","duration":"217.922391ms","start":"2026-03-25T07:36:28.723778Z","end":"2026-03-25T07:36:28.9417Z","steps":["trace[1328316728] 'agreement among raft nodes before linearized reading' (duration: 217.667165ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:28.941986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.858972ms","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-25T07:36:28.942084Z","caller":"traceutil/trace.go:171","msg":"trace[1311480877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1032; }","duration":"421.983146ms","start":"2026-03-25T07:36:28.520054Z","end":"2026-03-25T07:36:28.942037Z","steps":["trace[1311480877] 'agreement among raft nodes before linearized reading' (duration: 421.827902ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:28.942196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:28.520035Z","time spent":"422.083099ms","remote":"127.0.0.1:49058","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-25T07:36:29.419994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.543085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:36:29.420162Z","caller":"traceutil/trace.go:171","msg":"trace[542678807] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"474.223174ms","start":"2026-03-25T07:36:28.945909Z","end":"2026-03-25T07:36:29.420132Z","steps":["trace[542678807] 'process raft request' (duration: 107.426793ms)","trace[542678807] 'compare' (duration: 366.34497ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:36:29.420649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:28.94589Z","time spent":"474.329696ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:36:29.420848Z","caller":"traceutil/trace.go:171","msg":"trace[1897290471] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"474.717237ms","start":"2026-03-25T07:36:28.946101Z","end":"2026-03-25T07:36:29.420818Z","steps":["trace[1897290471] 'process raft request' (duration: 474.594214ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:36:29.420882Z","caller":"traceutil/trace.go:171","msg":"trace[925531352] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"474.770948ms","start":"2026-03-25T07:36:28.946086Z","end":"2026-03-25T07:36:29.420857Z","steps":["trace[925531352] 'process raft request' (duration: 474.43855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:29.421078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:28.946083Z","time spent":"474.882342ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:36:29.421025Z","caller":"traceutil/trace.go:171","msg":"trace[603782589] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"474.127252ms","start":"2026-03-25T07:36:28.946878Z","end":"2026-03-25T07:36:29.421005Z","steps":["trace[603782589] 'process raft request' (duration: 473.862265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:29.421219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:28.946854Z","time spent":"474.301946ms","remote":"127.0.0.1:49058","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-25T07:36:29.421423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:28.946065Z","time spent":"475.039275ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:36:29.421883Z","caller":"traceutil/trace.go:171","msg":"trace[528690333] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"474.900721ms","start":"2026-03-25T07:36:28.946967Z","end":"2026-03-25T07:36:29.421868Z","steps":["trace[528690333] 'process raft request' (duration: 473.956818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:29.421958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:28.946961Z","time spent":"474.960842ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:36:29.422154Z","caller":"traceutil/trace.go:171","msg":"trace[1777064826] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"474.946582ms","start":"2026-03-25T07:36:28.94718Z","end":"2026-03-25T07:36:29.422126Z","steps":["trace[1777064826] 'process raft request' (duration: 474.636334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:29.422302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:28.94717Z","time spent":"475.045675ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:36:29.422316Z","caller":"traceutil/trace.go:171","msg":"trace[1917362402] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1069; }","duration":"313.092646ms","start":"2026-03-25T07:36:29.109172Z","end":"2026-03-25T07:36:29.422264Z","steps":["trace[1917362402] 'read index received' (duration: 311.323802ms)","trace[1917362402] 'applied index is now lower than readState.Index' (duration: 1.767444ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:36:29.422421Z","caller":"traceutil/trace.go:171","msg":"trace[121068357] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"436.901389ms","start":"2026-03-25T07:36:28.985503Z","end":"2026-03-25T07:36:29.422405Z","steps":["trace[121068357] 'process raft request' (duration: 436.53821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:29.422487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:28.985486Z","time spent":"436.973511ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:36:29.422503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.331362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:36:29.422635Z","caller":"traceutil/trace.go:171","msg":"trace[1657467074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1039; }","duration":"313.497776ms","start":"2026-03-25T07:36:29.109124Z","end":"2026-03-25T07:36:29.422622Z","steps":["trace[1657467074] 'agreement among raft nodes before linearized reading' (duration: 313.238219ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:29.422713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:29.109107Z","time spent":"313.560248ms","remote":"127.0.0.1:48796","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-25T07:38:24.307295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.05477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.221\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-25T07:38:24.307433Z","caller":"traceutil/trace.go:171","msg":"trace[982189760] range","detail":"{range_begin:/registry/masterleases/199.19.213.221; range_end:; response_count:1; response_revision:1506; }","duration":"123.225425ms","start":"2026-03-25T07:38:24.184178Z","end":"2026-03-25T07:38:24.307403Z","steps":["trace[982189760] 'range keys from in-memory index tree' (duration: 122.807785ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:39:15.463073Z","caller":"traceutil/trace.go:171","msg":"trace[2013301153] transaction","detail":"{read_only:false; response_revision:1713; number_of_response:1; }","duration":"121.648187ms","start":"2026-03-25T07:39:15.341399Z","end":"2026-03-25T07:39:15.463048Z","steps":["trace[2013301153] 'process raft request' (duration: 121.464643ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:39:20.476883Z","caller":"traceutil/trace.go:171","msg":"trace[2022284299] linearizableReadLoop","detail":"{readStateIndex:1801; appliedIndex:1800; }","duration":"134.775814ms","start":"2026-03-25T07:39:20.342085Z","end":"2026-03-25T07:39:20.476861Z","steps":["trace[2022284299] 'read index received' (duration: 134.60579ms)","trace[2022284299] 'applied index is now lower than readState.Index' (duration: 169.344µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:39:20.476937Z","caller":"traceutil/trace.go:171","msg":"trace[466844155] transaction","detail":"{read_only:false; response_revision:1730; number_of_response:1; }","duration":"137.546313ms","start":"2026-03-25T07:39:20.339356Z","end":"2026-03-25T07:39:20.476902Z","steps":["trace[466844155] 'process raft request' (duration: 137.383619ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:39:20.477099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.00059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-25T07:39:20.477128Z","caller":"traceutil/trace.go:171","msg":"trace[782957735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1730; }","duration":"135.086162ms","start":"2026-03-25T07:39:20.342033Z","end":"2026-03-25T07:39:20.477119Z","steps":["trace[782957735] 'agreement among raft nodes before linearized reading' (duration: 134.953469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:39:20.477136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.445587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-25T07:39:20.4772Z","caller":"traceutil/trace.go:171","msg":"trace[1612268760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1730; }","duration":"128.535559ms","start":"2026-03-25T07:39:20.348645Z","end":"2026-03-25T07:39:20.477181Z","steps":["trace[1612268760] 'agreement among raft nodes before linearized reading' (duration: 128.371775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:39:20.477186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.274712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-25T07:39:20.477261Z","caller":"traceutil/trace.go:171","msg":"trace[390564015] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1730; }","duration":"124.382855ms","start":"2026-03-25T07:39:20.352857Z","end":"2026-03-25T07:39:20.47724Z","steps":["trace[390564015] 'agreement among raft nodes before linearized reading' (duration: 124.218711ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:39:20.477414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.724383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-25T07:39:20.477439Z","caller":"traceutil/trace.go:171","msg":"trace[1423079976] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1730; }","duration":"128.796246ms","start":"2026-03-25T07:39:20.348635Z","end":"2026-03-25T07:39:20.477431Z","steps":["trace[1423079976] 'agreement among raft nodes before linearized reading' (duration: 128.723934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:39:20.47757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.444107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-25T07:39:20.477598Z","caller":"traceutil/trace.go:171","msg":"trace[2094598538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1730; }","duration":"128.483928ms","start":"2026-03-25T07:39:20.349107Z","end":"2026-03-25T07:39:20.477591Z","steps":["trace[2094598538] 'agreement among raft nodes before linearized reading' (duration: 128.412926ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:39:28.714888Z","caller":"traceutil/trace.go:171","msg":"trace[1958274849] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"117.558515ms","start":"2026-03-25T07:39:28.597301Z","end":"2026-03-25T07:39:28.714859Z","steps":["trace[1958274849] 'process raft request' (duration: 99.271389ms)","trace[1958274849] 'compare' (duration: 18.129432ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:39:28.715125Z","caller":"traceutil/trace.go:171","msg":"trace[1958109459] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"117.370471ms","start":"2026-03-25T07:39:28.597692Z","end":"2026-03-25T07:39:28.715062Z","steps":["trace[1958109459] 'process raft request' (duration: 117.125915ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:39:28.715308Z","caller":"traceutil/trace.go:171","msg":"trace[1050423902] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"117.448303ms","start":"2026-03-25T07:39:28.597781Z","end":"2026-03-25T07:39:28.71523Z","steps":["trace[1050423902] 'process raft request' (duration: 117.184356ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:39:59.730913Z","caller":"traceutil/trace.go:171","msg":"trace[2002762559] linearizableReadLoop","detail":"{readStateIndex:2012; appliedIndex:2011; }","duration":"135.79787ms","start":"2026-03-25T07:39:59.595096Z","end":"2026-03-25T07:39:59.730894Z","steps":["trace[2002762559] 'read index received' (duration: 93.714913ms)","trace[2002762559] 'applied index is now lower than readState.Index' (duration: 42.081497ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:39:59.73104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.911243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/kube-node-lease/\" range_end:\"/registry/ingress/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:39:59.731128Z","caller":"traceutil/trace.go:171","msg":"trace[1729013302] range","detail":"{range_begin:/registry/ingress/kube-node-lease/; range_end:/registry/ingress/kube-node-lease0; response_count:0; response_revision:1932; }","duration":"136.015496ms","start":"2026-03-25T07:39:59.595093Z","end":"2026-03-25T07:39:59.731109Z","steps":["trace[1729013302] 'agreement among raft nodes before linearized reading' (duration: 135.866131ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:40:01.813103Z","caller":"traceutil/trace.go:171","msg":"trace[795275722] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"125.814449ms","start":"2026-03-25T07:40:01.687262Z","end":"2026-03-25T07:40:01.813076Z","steps":["trace[795275722] 'process raft request' (duration: 125.635425ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:40:03.914002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.371519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:132 size:101684"} {"level":"info","ts":"2026-03-25T07:40:03.914119Z","caller":"traceutil/trace.go:171","msg":"trace[649290446] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:132; response_revision:1950; }","duration":"123.531264ms","start":"2026-03-25T07:40:03.790564Z","end":"2026-03-25T07:40:03.914096Z","steps":["trace[649290446] 'range keys from in-memory index tree' (duration: 122.712443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:40:03.914121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.30393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:132 size:101684"} {"level":"info","ts":"2026-03-25T07:40:03.91422Z","caller":"traceutil/trace.go:171","msg":"trace[1877261384] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:132; response_revision:1950; }","duration":"121.435603ms","start":"2026-03-25T07:40:03.792759Z","end":"2026-03-25T07:40:03.914195Z","steps":["trace[1877261384] 'range keys from in-memory index tree' (duration: 120.50015ms)"],"step_count":1}