{"level":"warn","ts":"2026-03-31T10:59:37.529889Z","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-31T10:59:37.529967Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-31T10:59:37.530033Z","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-31T10:59:37.530046Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-03-31T10:59:37.530062Z","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-31T10:59:37.530684Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-03-31T10:59:37.53076Z","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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-31T10:59:37.536902Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.935646ms"} {"level":"info","ts":"2026-03-31T10:59:37.546593Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-03-31T10:59:37.546668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T10:59:37.546688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-03-31T10:59:37.546697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T10:59:37.546705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-03-31T10:59:37.546739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-03-31T10:59:37.558767Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T10:59:37.560203Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T10:59:37.561544Z","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-31T10:59:37.566665Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T10:59:37.566755Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T10:59:37.567173Z","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-31T10:59:37.567937Z","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-31T10:59:37.568039Z","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-31T10:59:37.570614Z","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-31T10:59:37.570811Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T10:59:37.570845Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T10:59:37.570906Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-03-31T10:59:37.570917Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-03-31T10:59:37.572076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-03-31T10:59:37.572174Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-03-31T10:59:38.246904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T10:59:38.246957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T10:59:38.246974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-03-31T10:59:38.246986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-03-31T10:59:38.246993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-03-31T10:59:38.247004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-03-31T10:59:38.247017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-03-31T10:59:38.247953Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T10:59:38.247961Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T10:59:38.248047Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T10:59:38.248104Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T10:59:38.248178Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T10:59:38.248198Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T10:59:38.248612Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T10:59:38.248666Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T10:59:38.248687Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T10:59:38.249735Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-31T10:59:38.252529Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-03-31T11:00:06.508276Z","caller":"traceutil/trace.go:171","msg":"trace[1323112428] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"372.048672ms","start":"2026-03-31T11:00:06.1362Z","end":"2026-03-31T11:00:06.508249Z","steps":["trace[1323112428] 'process raft request' (duration: 371.880968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:06.50909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:06.136182Z","time spent":"372.231836ms","remote":"127.0.0.1:33626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:00:06.521078Z","caller":"traceutil/trace.go:171","msg":"trace[791217764] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"372.353179ms","start":"2026-03-31T11:00:06.148676Z","end":"2026-03-31T11:00:06.52103Z","steps":["trace[791217764] 'process raft request' (duration: 372.176045ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:00:06.521116Z","caller":"traceutil/trace.go:171","msg":"trace[1488203563] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"372.305668ms","start":"2026-03-31T11:00:06.148769Z","end":"2026-03-31T11:00:06.521074Z","steps":["trace[1488203563] 'process raft request' (duration: 372.134724ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:00:06.521139Z","caller":"traceutil/trace.go:171","msg":"trace[430392890] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"382.077789ms","start":"2026-03-31T11:00:06.138985Z","end":"2026-03-31T11:00:06.521063Z","steps":["trace[430392890] 'process raft request' (duration: 381.72023ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:06.521249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:06.148656Z","time spent":"372.512803ms","remote":"127.0.0.1:33626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T11:00:06.521246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:06.148765Z","time spent":"372.40912ms","remote":"127.0.0.1:33626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:00:06.521439Z","caller":"traceutil/trace.go:171","msg":"trace[2071601319] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"372.322508ms","start":"2026-03-31T11:00:06.148847Z","end":"2026-03-31T11:00:06.52117Z","steps":["trace[2071601319] 'process raft request' (duration: 372.095423ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:06.521474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:06.138967Z","time spent":"382.328025ms","remote":"127.0.0.1:33626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T11:00:06.521568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:06.148841Z","time spent":"372.650456ms","remote":"127.0.0.1:33626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:00:06.521029Z","caller":"traceutil/trace.go:171","msg":"trace[1926293964] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"380.826367ms","start":"2026-03-31T11:00:06.140181Z","end":"2026-03-31T11:00:06.521007Z","steps":["trace[1926293964] 'process raft request' (duration: 380.633743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:06.521897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:06.140153Z","time spent":"381.699369ms","remote":"127.0.0.1:33626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:00:06.522041Z","caller":"traceutil/trace.go:171","msg":"trace[1281363496] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"372.366319ms","start":"2026-03-31T11:00:06.149661Z","end":"2026-03-31T11:00:06.522028Z","steps":["trace[1281363496] 'process raft request' (duration: 372.213385ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:06.522141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:06.149655Z","time spent":"372.440021ms","remote":"127.0.0.1:33626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:00:06.522442Z","caller":"traceutil/trace.go:171","msg":"trace[961407221] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"353.587897ms","start":"2026-03-31T11:00:06.168829Z","end":"2026-03-31T11:00:06.522417Z","steps":["trace[961407221] 'process raft request' (duration: 353.291059ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:00:06.522487Z","caller":"traceutil/trace.go:171","msg":"trace[773714764] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"319.979749ms","start":"2026-03-31T11:00:06.202483Z","end":"2026-03-31T11:00:06.522463Z","steps":["trace[773714764] 'process raft request' (duration: 319.687482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:06.522553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:06.202462Z","time spent":"320.059941ms","remote":"127.0.0.1:33626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T11:00:06.522503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:06.168798Z","time spent":"353.680099ms","remote":"127.0.0.1:33626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:00:06.522751Z","caller":"traceutil/trace.go:171","msg":"trace[103538412] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"189.808343ms","start":"2026-03-31T11:00:06.332929Z","end":"2026-03-31T11:00:06.522737Z","steps":["trace[103538412] 'process raft request' (duration: 189.302651ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:00:06.522859Z","caller":"traceutil/trace.go:171","msg":"trace[362268019] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"372.8096ms","start":"2026-03-31T11:00:06.149993Z","end":"2026-03-31T11:00:06.522802Z","steps":["trace[362268019] 'process raft request' (duration: 371.946529ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:00:06.522942Z","caller":"traceutil/trace.go:171","msg":"trace[183871278] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"364.860494ms","start":"2026-03-31T11:00:06.158071Z","end":"2026-03-31T11:00:06.522931Z","steps":["trace[183871278] 'process raft request' (duration: 364.000713ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:06.522957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:06.149988Z","time spent":"372.904002ms","remote":"127.0.0.1:33626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T11:00:06.523003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:06.158024Z","time spent":"364.952956ms","remote":"127.0.0.1:33626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:00:13.116793Z","caller":"traceutil/trace.go:171","msg":"trace[748963628] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"245.577608ms","start":"2026-03-31T11:00:12.871188Z","end":"2026-03-31T11:00:13.116765Z","steps":["trace[748963628] 'process raft request' (duration: 244.963433ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:00:13.384119Z","caller":"traceutil/trace.go:171","msg":"trace[1312343098] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"394.512185ms","start":"2026-03-31T11:00:12.989585Z","end":"2026-03-31T11:00:13.384097Z","steps":["trace[1312343098] 'read index received' (duration: 126.075445ms)","trace[1312343098] 'applied index is now lower than readState.Index' (duration: 268.43586ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:00:13.384228Z","caller":"traceutil/trace.go:171","msg":"trace[832447446] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"506.144193ms","start":"2026-03-31T11:00:12.878047Z","end":"2026-03-31T11:00:13.384191Z","steps":["trace[832447446] 'process raft request' (duration: 505.941988ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:13.384271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.668328ms","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-31T11:00:13.384326Z","caller":"traceutil/trace.go:171","msg":"trace[119378241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"394.770461ms","start":"2026-03-31T11:00:12.989548Z","end":"2026-03-31T11:00:13.384319Z","steps":["trace[119378241] 'agreement among raft nodes before linearized reading' (duration: 394.631168ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:13.384348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:12.989527Z","time spent":"394.816092ms","remote":"127.0.0.1:33846","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-31T11:00:13.384638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:12.878012Z","time spent":"506.320208ms","remote":"127.0.0.1:33776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:00:13.42865Z","caller":"traceutil/trace.go:171","msg":"trace[1313266000] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"257.089889ms","start":"2026-03-31T11:00:13.171528Z","end":"2026-03-31T11:00:13.428618Z","steps":["trace[1313266000] 'process raft request' (duration: 256.945626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:13.965884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764402355523352,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T11:00:14.280121Z","caller":"traceutil/trace.go:171","msg":"trace[1615961824] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"818.930685ms","start":"2026-03-31T11:00:13.461167Z","end":"2026-03-31T11:00:14.280098Z","steps":["trace[1615961824] 'process raft request' (duration: 818.803872ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:14.280327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:13.461153Z","time spent":"819.113989ms","remote":"127.0.0.1:33776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:00:14.292293Z","caller":"traceutil/trace.go:171","msg":"trace[1809181733] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"783.840192ms","start":"2026-03-31T11:00:13.508395Z","end":"2026-03-31T11:00:14.292235Z","steps":["trace[1809181733] 'process raft request' (duration: 783.696708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:14.29243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:13.50836Z","time spent":"783.999456ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:00:14.2924Z","caller":"traceutil/trace.go:171","msg":"trace[1027094708] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:641; }","duration":"827.108006ms","start":"2026-03-31T11:00:13.46527Z","end":"2026-03-31T11:00:14.292378Z","steps":["trace[1027094708] 'read index received' (duration: 814.878504ms)","trace[1027094708] 'applied index is now lower than readState.Index' (duration: 12.228362ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:00:14.292552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.285811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:00:14.29262Z","caller":"traceutil/trace.go:171","msg":"trace[2077022427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"827.375273ms","start":"2026-03-31T11:00:13.465234Z","end":"2026-03-31T11:00:14.292609Z","steps":["trace[2077022427] 'agreement among raft nodes before linearized reading' (duration: 827.286211ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:14.292645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:13.465223Z","time spent":"827.416174ms","remote":"127.0.0.1:33572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T11:00:14.292501Z","caller":"traceutil/trace.go:171","msg":"trace[2073963507] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"736.469055ms","start":"2026-03-31T11:00:13.555978Z","end":"2026-03-31T11:00:14.292447Z","steps":["trace[2073963507] 'process raft request' (duration: 736.217329ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:14.293406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:13.555944Z","time spent":"737.381937ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:00:14.30018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.157438ms","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-31T11:00:14.30024Z","caller":"traceutil/trace.go:171","msg":"trace[1498047556] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"695.249371ms","start":"2026-03-31T11:00:13.604976Z","end":"2026-03-31T11:00:14.300225Z","steps":["trace[1498047556] 'agreement among raft nodes before linearized reading' (duration: 695.085827ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:14.300273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:13.604955Z","time spent":"695.310563ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T11:00:14.300547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.624054ms","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-31T11:00:14.300584Z","caller":"traceutil/trace.go:171","msg":"trace[1333739823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"456.686955ms","start":"2026-03-31T11:00:13.843889Z","end":"2026-03-31T11:00:14.300576Z","steps":["trace[1333739823] 'agreement among raft nodes before linearized reading' (duration: 456.596473ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:14.30061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:13.843876Z","time spent":"456.728567ms","remote":"127.0.0.1:33846","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-31T11:00:14.30076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.001077ms","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-31T11:00:14.300792Z","caller":"traceutil/trace.go:171","msg":"trace[971334278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"473.059329ms","start":"2026-03-31T11:00:13.827724Z","end":"2026-03-31T11:00:14.300784Z","steps":["trace[971334278] 'agreement among raft nodes before linearized reading' (duration: 472.980357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:14.300817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:13.827705Z","time spent":"473.10667ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T11:00:19.359452Z","caller":"traceutil/trace.go:171","msg":"trace[784875331] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"156.090164ms","start":"2026-03-31T11:00:19.203334Z","end":"2026-03-31T11:00:19.359424Z","steps":["trace[784875331] 'process raft request' (duration: 155.845527ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:00:30.928192Z","caller":"traceutil/trace.go:171","msg":"trace[1514591723] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"328.764295ms","start":"2026-03-31T11:00:30.599403Z","end":"2026-03-31T11:00:30.928167Z","steps":["trace[1514591723] 'process raft request' (duration: 328.55256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:00:30.928515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:00:30.599379Z","time spent":"328.891158ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:01:04.747278Z","caller":"traceutil/trace.go:171","msg":"trace[1650297721] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"134.618387ms","start":"2026-03-31T11:01:04.612641Z","end":"2026-03-31T11:01:04.74726Z","steps":["trace[1650297721] 'read index received' (duration: 134.513253ms)","trace[1650297721] 'applied index is now lower than readState.Index' (duration: 104.474µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:01:04.747513Z","caller":"traceutil/trace.go:171","msg":"trace[893325950] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"158.906675ms","start":"2026-03-31T11:01:04.588598Z","end":"2026-03-31T11:01:04.747504Z","steps":["trace[893325950] 'process raft request' (duration: 158.570737ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:01:04.747619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.961115ms","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-31T11:01:04.747651Z","caller":"traceutil/trace.go:171","msg":"trace[667874418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:998; }","duration":"135.005306ms","start":"2026-03-31T11:01:04.612637Z","end":"2026-03-31T11:01:04.747642Z","steps":["trace[667874418] 'agreement among raft nodes before linearized reading' (duration: 134.879633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:01:04.74773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.1537ms","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-31T11:01:04.747855Z","caller":"traceutil/trace.go:171","msg":"trace[1667942167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:998; }","duration":"133.316955ms","start":"2026-03-31T11:01:04.614519Z","end":"2026-03-31T11:01:04.747836Z","steps":["trace[1667942167] 'agreement among raft nodes before linearized reading' (duration: 133.030068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:01:05.170467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.80126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:01:05.170595Z","caller":"traceutil/trace.go:171","msg":"trace[1438567421] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"419.010453ms","start":"2026-03-31T11:01:04.751564Z","end":"2026-03-31T11:01:05.170575Z","steps":["trace[1438567421] 'process raft request' (duration: 195.828445ms)","trace[1438567421] 'compare' (duration: 222.647814ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:01:05.170667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:01:04.751543Z","time spent":"419.099535ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:01:05.264193Z","caller":"traceutil/trace.go:171","msg":"trace[2038137573] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"511.938842ms","start":"2026-03-31T11:01:04.752235Z","end":"2026-03-31T11:01:05.264174Z","steps":["trace[2038137573] 'process raft request' (duration: 511.826479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:01:05.264301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:01:04.752221Z","time spent":"512.027374ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:01:05.265654Z","caller":"traceutil/trace.go:171","msg":"trace[306644314] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"220.685527ms","start":"2026-03-31T11:01:05.044951Z","end":"2026-03-31T11:01:05.265637Z","steps":["trace[306644314] 'process raft request' (duration: 220.595025ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:01:25.424383Z","caller":"traceutil/trace.go:171","msg":"trace[296153269] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"170.610833ms","start":"2026-03-31T11:01:25.253751Z","end":"2026-03-31T11:01:25.424362Z","steps":["trace[296153269] 'process raft request' (duration: 170.46843ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:01:25.577344Z","caller":"traceutil/trace.go:171","msg":"trace[329094577] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1101; }","duration":"228.307014ms","start":"2026-03-31T11:01:25.349008Z","end":"2026-03-31T11:01:25.577315Z","steps":["trace[329094577] 'read index received' (duration: 75.249524ms)","trace[329094577] 'applied index is now lower than readState.Index' (duration: 153.05652ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:01:25.577402Z","caller":"traceutil/trace.go:171","msg":"trace[205431752] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"249.092337ms","start":"2026-03-31T11:01:25.32828Z","end":"2026-03-31T11:01:25.577373Z","steps":["trace[205431752] 'process raft request' (duration: 216.783312ms)","trace[205431752] 'compare' (duration: 32.135091ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:01:25.577546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.527179ms","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-31T11:01:25.577596Z","caller":"traceutil/trace.go:171","msg":"trace[582301448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1066; }","duration":"228.622581ms","start":"2026-03-31T11:01:25.34895Z","end":"2026-03-31T11:01:25.577572Z","steps":["trace[582301448] 'agreement among raft nodes before linearized reading' (duration: 228.469107ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:01:25.799396Z","caller":"traceutil/trace.go:171","msg":"trace[301679335] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"217.900018ms","start":"2026-03-31T11:01:25.581464Z","end":"2026-03-31T11:01:25.799364Z","steps":["trace[301679335] 'process raft request' (duration: 191.073647ms)","trace[301679335] 'compare' (duration: 26.594395ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:01:27.17427Z","caller":"traceutil/trace.go:171","msg":"trace[799477474] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"252.340347ms","start":"2026-03-31T11:01:26.921905Z","end":"2026-03-31T11:01:27.174245Z","steps":["trace[799477474] 'process raft request' (duration: 252.182043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:01:39.905196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.821761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:01:39.905539Z","caller":"traceutil/trace.go:171","msg":"trace[1814389013] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"250.879411ms","start":"2026-03-31T11:01:39.654639Z","end":"2026-03-31T11:01:39.905518Z","steps":["trace[1814389013] 'process raft request' (duration: 87.63994ms)","trace[1814389013] 'compare' (duration: 162.712358ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:01:43.820962Z","caller":"traceutil/trace.go:171","msg":"trace[1574707130] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1217; }","duration":"463.892318ms","start":"2026-03-31T11:01:43.357041Z","end":"2026-03-31T11:01:43.820933Z","steps":["trace[1574707130] 'read index received' (duration: 463.731783ms)","trace[1574707130] 'applied index is now lower than readState.Index' (duration: 159.665µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:01:43.821021Z","caller":"traceutil/trace.go:171","msg":"trace[62914531] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"485.993033ms","start":"2026-03-31T11:01:43.334994Z","end":"2026-03-31T11:01:43.820987Z","steps":["trace[62914531] 'process raft request' (duration: 485.768096ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:01:43.821117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.044702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:01:43.821174Z","caller":"traceutil/trace.go:171","msg":"trace[830953125] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1175; }","duration":"464.139524ms","start":"2026-03-31T11:01:43.357019Z","end":"2026-03-31T11:01:43.821159Z","steps":["trace[830953125] 'agreement among raft nodes before linearized reading' (duration: 464.033851ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:01:43.821215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:01:43.35701Z","time spent":"464.194836ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:01:43.82128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:01:43.334981Z","time spent":"486.162057ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:01:43.821274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.759329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T11:01:43.821307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.732203ms","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-31T11:01:43.821356Z","caller":"traceutil/trace.go:171","msg":"trace[633927477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1175; }","duration":"354.901563ms","start":"2026-03-31T11:01:43.466433Z","end":"2026-03-31T11:01:43.821335Z","steps":["trace[633927477] 'agreement among raft nodes before linearized reading' (duration: 354.731409ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:01:43.821363Z","caller":"traceutil/trace.go:171","msg":"trace[1342346556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1175; }","duration":"275.823335ms","start":"2026-03-31T11:01:43.545523Z","end":"2026-03-31T11:01:43.821346Z","steps":["trace[1342346556] 'agreement among raft nodes before linearized reading' (duration: 275.649171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:01:43.821403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:01:43.466417Z","time spent":"354.977195ms","remote":"127.0.0.1:33578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T11:01:44.090143Z","caller":"traceutil/trace.go:171","msg":"trace[1478027804] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"264.946938ms","start":"2026-03-31T11:01:43.825174Z","end":"2026-03-31T11:01:44.090121Z","steps":["trace[1478027804] 'process raft request' (duration: 264.817084ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:01:44.091268Z","caller":"traceutil/trace.go:171","msg":"trace[1894562819] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1218; }","duration":"164.661487ms","start":"2026-03-31T11:01:43.92659Z","end":"2026-03-31T11:01:44.091252Z","steps":["trace[1894562819] 'read index received' (duration: 163.402186ms)","trace[1894562819] 'applied index is now lower than readState.Index' (duration: 1.258361ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:01:44.091366Z","caller":"traceutil/trace.go:171","msg":"trace[1243432367] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"175.682098ms","start":"2026-03-31T11:01:43.915659Z","end":"2026-03-31T11:01:44.091341Z","steps":["trace[1243432367] 'process raft request' (duration: 175.501914ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:01:44.091597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.725863ms","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-31T11:01:44.091705Z","caller":"traceutil/trace.go:171","msg":"trace[242838497] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1177; }","duration":"109.847666ms","start":"2026-03-31T11:01:43.981814Z","end":"2026-03-31T11:01:44.091661Z","steps":["trace[242838497] 'agreement among raft nodes before linearized reading' (duration: 109.655761ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:01:44.091734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.128768ms","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-31T11:01:44.091773Z","caller":"traceutil/trace.go:171","msg":"trace[583200042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1177; }","duration":"165.21259ms","start":"2026-03-31T11:01:43.926548Z","end":"2026-03-31T11:01:44.091761Z","steps":["trace[583200042] 'agreement among raft nodes before linearized reading' (duration: 165.080557ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:01:58.103679Z","caller":"traceutil/trace.go:171","msg":"trace[925429177] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"186.884894ms","start":"2026-03-31T11:01:57.916771Z","end":"2026-03-31T11:01:58.103656Z","steps":["trace[925429177] 'process raft request' (duration: 186.705389ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:01:58.595112Z","caller":"traceutil/trace.go:171","msg":"trace[991602064] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1275; }","duration":"445.604987ms","start":"2026-03-31T11:01:58.149485Z","end":"2026-03-31T11:01:58.59509Z","steps":["trace[991602064] 'read index received' (duration: 445.454273ms)","trace[991602064] 'applied index is now lower than readState.Index' (duration: 149.794µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:01:58.595152Z","caller":"traceutil/trace.go:171","msg":"trace[132577039] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"459.670084ms","start":"2026-03-31T11:01:58.135458Z","end":"2026-03-31T11:01:58.595128Z","steps":["trace[132577039] 'process raft request' (duration: 459.50365ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:01:58.595265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:01:58.135432Z","time spent":"459.771746ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:01:58.595312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.721491ms","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-31T11:01:58.595338Z","caller":"traceutil/trace.go:171","msg":"trace[34004708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1230; }","duration":"445.802773ms","start":"2026-03-31T11:01:58.149528Z","end":"2026-03-31T11:01:58.595331Z","steps":["trace[34004708] 'agreement among raft nodes before linearized reading' (duration: 445.680659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:01:58.595377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:01:58.149509Z","time spent":"445.861195ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T11:01:58.595408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.692636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T11:01:58.595448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.949716ms","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-31T11:01:58.595476Z","caller":"traceutil/trace.go:171","msg":"trace[1883543609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1230; }","duration":"129.808248ms","start":"2026-03-31T11:01:58.465652Z","end":"2026-03-31T11:01:58.595461Z","steps":["trace[1883543609] 'agreement among raft nodes before linearized reading' (duration: 129.625264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:01:58.595476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.739098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-31T11:01:58.595578Z","caller":"traceutil/trace.go:171","msg":"trace[805117135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1230; }","duration":"425.894301ms","start":"2026-03-31T11:01:58.169666Z","end":"2026-03-31T11:01:58.59556Z","steps":["trace[805117135] 'agreement among raft nodes before linearized reading' (duration: 425.613225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:01:58.595557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.752147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-31T11:01:58.595626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:01:58.169645Z","time spent":"425.972244ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-31T11:01:58.595643Z","caller":"traceutil/trace.go:171","msg":"trace[583182708] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1230; }","duration":"442.85996ms","start":"2026-03-31T11:01:58.15276Z","end":"2026-03-31T11:01:58.59562Z","steps":["trace[583182708] 'agreement among raft nodes before linearized reading' (duration: 442.560133ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:01:58.595689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:01:58.152754Z","time spent":"442.927311ms","remote":"127.0.0.1:33846","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":"info","ts":"2026-03-31T11:01:58.595986Z","caller":"traceutil/trace.go:171","msg":"trace[1570722280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1230; }","duration":"446.033889ms","start":"2026-03-31T11:01:58.149454Z","end":"2026-03-31T11:01:58.595488Z","steps":["trace[1570722280] 'agreement among raft nodes before linearized reading' (duration: 445.744391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:01:58.596162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:01:58.149439Z","time spent":"446.598682ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T11:01:58.75639Z","caller":"traceutil/trace.go:171","msg":"trace[750964692] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"157.065019ms","start":"2026-03-31T11:01:58.599293Z","end":"2026-03-31T11:01:58.756358Z","steps":["trace[750964692] 'process raft request' (duration: 57.776094ms)","trace[750964692] 'compare' (duration: 99.07462ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:01:58.756715Z","caller":"traceutil/trace.go:171","msg":"trace[263689595] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"157.1332ms","start":"2026-03-31T11:01:58.599556Z","end":"2026-03-31T11:01:58.75669Z","steps":["trace[263689595] 'process raft request' (duration: 156.73356ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:01:58.756909Z","caller":"traceutil/trace.go:171","msg":"trace[228318053] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"156.803713ms","start":"2026-03-31T11:01:58.599957Z","end":"2026-03-31T11:01:58.756761Z","steps":["trace[228318053] 'process raft request' (duration: 156.758172ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:01:58.757055Z","caller":"traceutil/trace.go:171","msg":"trace[21541274] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"157.458488ms","start":"2026-03-31T11:01:58.599562Z","end":"2026-03-31T11:01:58.757021Z","steps":["trace[21541274] 'process raft request' (duration: 157.051308ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:02:00.140495Z","caller":"traceutil/trace.go:171","msg":"trace[1101308630] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"169.527106ms","start":"2026-03-31T11:01:59.970942Z","end":"2026-03-31T11:02:00.140469Z","steps":["trace[1101308630] 'process raft request' (duration: 169.355431ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:02:00.741483Z","caller":"traceutil/trace.go:171","msg":"trace[2044904524] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"139.881276ms","start":"2026-03-31T11:02:00.60126Z","end":"2026-03-31T11:02:00.741142Z","steps":["trace[2044904524] 'process raft request' (duration: 139.714381ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:02:00.959955Z","caller":"traceutil/trace.go:171","msg":"trace[1436458530] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"196.504092ms","start":"2026-03-31T11:02:00.763388Z","end":"2026-03-31T11:02:00.959892Z","steps":["trace[1436458530] 'process raft request' (duration: 101.477241ms)","trace[1436458530] 'compare' (duration: 94.827636ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:02:00.960153Z","caller":"traceutil/trace.go:171","msg":"trace[778698055] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1286; }","duration":"193.136018ms","start":"2026-03-31T11:02:00.767005Z","end":"2026-03-31T11:02:00.960141Z","steps":["trace[778698055] 'read index received' (duration: 97.922092ms)","trace[778698055] 'applied index is now lower than readState.Index' (duration: 95.212946ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:02:00.960228Z","caller":"traceutil/trace.go:171","msg":"trace[1956547025] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"193.130498ms","start":"2026-03-31T11:02:00.76709Z","end":"2026-03-31T11:02:00.96022Z","steps":["trace[1956547025] 'process raft request' (duration: 192.987004ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:02:00.960246Z","caller":"traceutil/trace.go:171","msg":"trace[1070926834] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"195.551307ms","start":"2026-03-31T11:02:00.764649Z","end":"2026-03-31T11:02:00.960201Z","steps":["trace[1070926834] 'process raft request' (duration: 195.137447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:02:00.960556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.530267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-31T11:02:00.960654Z","caller":"traceutil/trace.go:171","msg":"trace[1924250412] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1243; }","duration":"193.663801ms","start":"2026-03-31T11:02:00.766967Z","end":"2026-03-31T11:02:00.960631Z","steps":["trace[1924250412] 'agreement among raft nodes before linearized reading' (duration: 193.367563ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:02:01.186944Z","caller":"traceutil/trace.go:171","msg":"trace[2089487473] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1290; }","duration":"128.539406ms","start":"2026-03-31T11:02:01.058377Z","end":"2026-03-31T11:02:01.186916Z","steps":["trace[2089487473] 'read index received' (duration: 128.402823ms)","trace[2089487473] 'applied index is now lower than readState.Index' (duration: 135.433µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:02:01.187026Z","caller":"traceutil/trace.go:171","msg":"trace[1348670079] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"219.222291ms","start":"2026-03-31T11:02:00.967779Z","end":"2026-03-31T11:02:01.187002Z","steps":["trace[1348670079] 'process raft request' (duration: 218.966395ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:02:01.187109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.721381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-31T11:02:01.187152Z","caller":"traceutil/trace.go:171","msg":"trace[2101064267] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1245; }","duration":"128.781573ms","start":"2026-03-31T11:02:01.05836Z","end":"2026-03-31T11:02:01.187141Z","steps":["trace[2101064267] 'agreement among raft nodes before linearized reading' (duration: 128.689521ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:02:06.474344Z","caller":"traceutil/trace.go:171","msg":"trace[16800732] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"287.589845ms","start":"2026-03-31T11:02:06.186694Z","end":"2026-03-31T11:02:06.474284Z","steps":["trace[16800732] 'process raft request' (duration: 287.469602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:02:07.286982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.338202ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-31T11:02:07.287382Z","caller":"traceutil/trace.go:171","msg":"trace[339373249] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"619.080651ms","start":"2026-03-31T11:02:06.668288Z","end":"2026-03-31T11:02:07.287368Z","steps":["trace[339373249] 'process raft request' (duration: 618.776164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:02:07.287464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:02:06.668259Z","time spent":"619.150143ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:02:07.287533Z","caller":"traceutil/trace.go:171","msg":"trace[1739579560] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1335; }","duration":"290.558787ms","start":"2026-03-31T11:02:06.996951Z","end":"2026-03-31T11:02:07.28751Z","steps":["trace[1739579560] 'read index received' (duration: 242.933443ms)","trace[1739579560] 'applied index is now lower than readState.Index' (duration: 47.608683ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:02:07.287574Z","caller":"traceutil/trace.go:171","msg":"trace[235972121] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"510.929676ms","start":"2026-03-31T11:02:06.77661Z","end":"2026-03-31T11:02:07.28754Z","steps":["trace[235972121] 'process raft request' (duration: 510.721271ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:02:07.287832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.825024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-31T11:02:07.287785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:02:06.776588Z","time spent":"511.06854ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:02:07.287892Z","caller":"traceutil/trace.go:171","msg":"trace[1648855929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1290; }","duration":"290.966908ms","start":"2026-03-31T11:02:06.996908Z","end":"2026-03-31T11:02:07.287875Z","steps":["trace[1648855929] 'agreement among raft nodes before linearized reading' (duration: 290.710041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:02:07.287937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.29406ms","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-31T11:02:07.287977Z","caller":"traceutil/trace.go:171","msg":"trace[943248030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1290; }","duration":"266.360733ms","start":"2026-03-31T11:02:07.021604Z","end":"2026-03-31T11:02:07.287964Z","steps":["trace[943248030] 'agreement among raft nodes before linearized reading' (duration: 266.255579ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:02:07.287956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.957817ms","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-31T11:02:07.28804Z","caller":"traceutil/trace.go:171","msg":"trace[1034344702] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1290; }","duration":"267.116411ms","start":"2026-03-31T11:02:07.020908Z","end":"2026-03-31T11:02:07.288024Z","steps":["trace[1034344702] 'agreement among raft nodes before linearized reading' (duration: 266.876644ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:02:07.464807Z","caller":"traceutil/trace.go:171","msg":"trace[1372387583] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"172.075619ms","start":"2026-03-31T11:02:07.292698Z","end":"2026-03-31T11:02:07.464774Z","steps":["trace[1372387583] 'process raft request' (duration: 171.963277ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:02:07.464883Z","caller":"traceutil/trace.go:171","msg":"trace[830368130] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"172.531751ms","start":"2026-03-31T11:02:07.292272Z","end":"2026-03-31T11:02:07.464804Z","steps":["trace[830368130] 'process raft request' (duration: 119.337731ms)","trace[830368130] 'compare' (duration: 52.561284ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:02:07.465054Z","caller":"traceutil/trace.go:171","msg":"trace[452556508] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"172.168221ms","start":"2026-03-31T11:02:07.292861Z","end":"2026-03-31T11:02:07.465029Z","steps":["trace[452556508] 'process raft request' (duration: 171.860924ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:02:07.465311Z","caller":"traceutil/trace.go:171","msg":"trace[1396926334] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"172.379997ms","start":"2026-03-31T11:02:07.292915Z","end":"2026-03-31T11:02:07.465295Z","steps":["trace[1396926334] 'process raft request' (duration: 172.07917ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:02:09.969586Z","caller":"traceutil/trace.go:171","msg":"trace[627522245] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"105.402107ms","start":"2026-03-31T11:02:09.864157Z","end":"2026-03-31T11:02:09.969559Z","steps":["trace[627522245] 'process raft request' (duration: 105.241843ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:02:11.82818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:02:11.142936Z","time spent":"685.237301ms","remote":"127.0.0.1:33616","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-31T11:02:11.831273Z","caller":"traceutil/trace.go:171","msg":"trace[465035958] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1372; }","duration":"488.660407ms","start":"2026-03-31T11:02:11.342588Z","end":"2026-03-31T11:02:11.831248Z","steps":["trace[465035958] 'read index received' (duration: 485.50291ms)","trace[465035958] 'applied index is now lower than readState.Index' (duration: 3.156647ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:02:11.83138Z","caller":"traceutil/trace.go:171","msg":"trace[456734767] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"531.347899ms","start":"2026-03-31T11:02:11.300002Z","end":"2026-03-31T11:02:11.83135Z","steps":["trace[456734767] 'process raft request' (duration: 531.070832ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:02:11.831618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:02:11.299974Z","time spent":"531.461372ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:02:11.831719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.280709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:02:11.831816Z","caller":"traceutil/trace.go:171","msg":"trace[1668727023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"365.439273ms","start":"2026-03-31T11:02:11.46634Z","end":"2026-03-31T11:02:11.83178Z","steps":["trace[1668727023] 'agreement among raft nodes before linearized reading' (duration: 365.268248ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:02:11.831856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:02:11.4663Z","time spent":"365.549646ms","remote":"127.0.0.1:33578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T11:02:11.831663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.791545ms","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-31T11:02:11.832036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.451158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-31T11:02:11.832133Z","caller":"traceutil/trace.go:171","msg":"trace[603001424] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1325; }","duration":"353.657103ms","start":"2026-03-31T11:02:11.478457Z","end":"2026-03-31T11:02:11.832114Z","steps":["trace[603001424] 'agreement among raft nodes before linearized reading' (duration: 353.112699ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:02:11.832236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:02:11.478428Z","time spent":"353.790467ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-31T11:02:11.832009Z","caller":"traceutil/trace.go:171","msg":"trace[1769269938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1325; }","duration":"343.167684ms","start":"2026-03-31T11:02:11.488821Z","end":"2026-03-31T11:02:11.831988Z","steps":["trace[1769269938] 'agreement among raft nodes before linearized reading' (duration: 342.726683ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:02:11.832345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.41762ms","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-31T11:02:11.832408Z","caller":"traceutil/trace.go:171","msg":"trace[1196903098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1325; }","duration":"343.498313ms","start":"2026-03-31T11:02:11.488893Z","end":"2026-03-31T11:02:11.832391Z","steps":["trace[1196903098] 'agreement among raft nodes before linearized reading' (duration: 342.752144ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:02:11.832464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:02:11.488886Z","time spent":"343.564914ms","remote":"127.0.0.1:33846","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-31T11:02:11.832404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:02:11.488807Z","time spent":"343.579325ms","remote":"127.0.0.1:33846","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-31T11:02:11.833187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.606606ms","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-31T11:02:11.833231Z","caller":"traceutil/trace.go:171","msg":"trace[1376017783] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1325; }","duration":"490.685638ms","start":"2026-03-31T11:02:11.342533Z","end":"2026-03-31T11:02:11.833219Z","steps":["trace[1376017783] 'agreement among raft nodes before linearized reading' (duration: 488.812642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:02:11.833254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:02:11.342516Z","time spent":"490.732929ms","remote":"127.0.0.1:33846","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-31T11:02:11.833179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.917135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T11:02:11.833357Z","caller":"traceutil/trace.go:171","msg":"trace[1913266863] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1325; }","duration":"487.0978ms","start":"2026-03-31T11:02:11.346236Z","end":"2026-03-31T11:02:11.833334Z","steps":["trace[1913266863] 'agreement among raft nodes before linearized reading' (duration: 485.268585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:02:11.833459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:02:11.346194Z","time spent":"487.242583ms","remote":"127.0.0.1:34082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-03-31T11:02:12.678461Z","caller":"traceutil/trace.go:171","msg":"trace[1381415958] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"181.789979ms","start":"2026-03-31T11:02:12.496633Z","end":"2026-03-31T11:02:12.678423Z","steps":["trace[1381415958] 'read index received' (duration: 181.617565ms)","trace[1381415958] 'applied index is now lower than readState.Index' (duration: 171.464µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:02:12.678501Z","caller":"traceutil/trace.go:171","msg":"trace[1310647806] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"204.366375ms","start":"2026-03-31T11:02:12.474115Z","end":"2026-03-31T11:02:12.678481Z","steps":["trace[1310647806] 'process raft request' (duration: 204.175701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:02:12.678638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.993103ms","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-31T11:02:12.678676Z","caller":"traceutil/trace.go:171","msg":"trace[136989353] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1332; }","duration":"182.085236ms","start":"2026-03-31T11:02:12.496581Z","end":"2026-03-31T11:02:12.678666Z","steps":["trace[136989353] 'agreement among raft nodes before linearized reading' (duration: 181.949212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:02:13.100643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.365585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-31T11:02:13.100728Z","caller":"traceutil/trace.go:171","msg":"trace[1016107435] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1333; }","duration":"338.487628ms","start":"2026-03-31T11:02:12.762218Z","end":"2026-03-31T11:02:13.100706Z","steps":["trace[1016107435] 'range keys from in-memory index tree' (duration: 338.218081ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:02:13.100842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:02:12.762199Z","time spent":"338.560259ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-31T11:02:13.28738Z","caller":"traceutil/trace.go:171","msg":"trace[926218972] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"182.064635ms","start":"2026-03-31T11:02:13.105289Z","end":"2026-03-31T11:02:13.287354Z","steps":["trace[926218972] 'process raft request' (duration: 181.891051ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:02:13.29081Z","caller":"traceutil/trace.go:171","msg":"trace[1783212068] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"128.567058ms","start":"2026-03-31T11:02:13.162218Z","end":"2026-03-31T11:02:13.290785Z","steps":["trace[1783212068] 'process raft request' (duration: 128.443145ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:03:54.342405Z","caller":"traceutil/trace.go:171","msg":"trace[180646464] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"115.885224ms","start":"2026-03-31T11:03:54.226494Z","end":"2026-03-31T11:03:54.342379Z","steps":["trace[180646464] 'process raft request' (duration: 115.693619ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:04:47.051041Z","caller":"traceutil/trace.go:171","msg":"trace[1991449227] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"101.77067ms","start":"2026-03-31T11:04:46.949233Z","end":"2026-03-31T11:04:47.051004Z","steps":["trace[1991449227] 'process raft request' (duration: 85.740128ms)","trace[1991449227] 'compare' (duration: 15.890469ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:05:25.235907Z","caller":"traceutil/trace.go:171","msg":"trace[1560810428] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"130.197537ms","start":"2026-03-31T11:05:25.105688Z","end":"2026-03-31T11:05:25.235886Z","steps":["trace[1560810428] 'process raft request' (duration: 130.058193ms)"],"step_count":1}