{"level":"warn","ts":"2026-03-09T13:35:14.755104Z","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-09T13:35:14.755231Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233: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.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-09T13:35:14.755509Z","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-09T13:35:14.755528Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]}
{"level":"info","ts":"2026-03-09T13:35:14.755651Z","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-09T13:35:14.756454Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]}
{"level":"info","ts":"2026-03-09T13:35:14.756601Z","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.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-09T13:35:14.761057Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.15125ms"}
{"level":"info","ts":"2026-03-09T13:35:14.766554Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"}
{"level":"info","ts":"2026-03-09T13:35:14.766737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"}
{"level":"info","ts":"2026-03-09T13:35:14.766776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"}
{"level":"info","ts":"2026-03-09T13:35:14.766803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-03-09T13:35:14.766821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"}
{"level":"info","ts":"2026-03-09T13:35:14.766914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"}
{"level":"warn","ts":"2026-03-09T13:35:14.772752Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-03-09T13:35:14.77403Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-03-09T13:35:14.775607Z","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-09T13:35:14.777583Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-03-09T13:35:14.77777Z","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-09T13:35:14.777838Z","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-09T13:35:14.777849Z","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-09T13:35:14.777829Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-03-09T13:35:14.778491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"}
{"level":"info","ts":"2026-03-09T13:35:14.778657Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]}
{"level":"info","ts":"2026-03-09T13:35:14.780597Z","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-09T13:35:14.780726Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"}
{"level":"info","ts":"2026-03-09T13:35:14.781594Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"}
{"level":"info","ts":"2026-03-09T13:35:14.780946Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-03-09T13:35:14.781027Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-03-09T13:35:15.168153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"}
{"level":"info","ts":"2026-03-09T13:35:15.168211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"}
{"level":"info","ts":"2026-03-09T13:35:15.168261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"}
{"level":"info","ts":"2026-03-09T13:35:15.168288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"}
{"level":"info","ts":"2026-03-09T13:35:15.168304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"}
{"level":"info","ts":"2026-03-09T13:35:15.168322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"}
{"level":"info","ts":"2026-03-09T13:35:15.168341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"}
{"level":"info","ts":"2026-03-09T13:35:15.169581Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"}
{"level":"info","ts":"2026-03-09T13:35:15.169581Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-09T13:35:15.169627Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-09T13:35:15.169624Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-09T13:35:15.170128Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-03-09T13:35:15.170176Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-03-09T13:35:15.170659Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-09T13:35:15.170808Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-09T13:35:15.170922Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-09T13:35:15.177664Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-03-09T13:35:15.17826Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"}
{"level":"info","ts":"2026-03-09T13:35:24.276418Z","caller":"traceutil/trace.go:171","msg":"trace[2146065697] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"280.268757ms","start":"2026-03-09T13:35:23.996116Z","end":"2026-03-09T13:35:24.276385Z","steps":["trace[2146065697] 'process raft request'  (duration: 280.096353ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T13:35:24.276393Z","caller":"traceutil/trace.go:171","msg":"trace[1209216263] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:352; }","duration":"207.181513ms","start":"2026-03-09T13:35:24.069166Z","end":"2026-03-09T13:35:24.276348Z","steps":["trace[1209216263] 'read index received'  (duration: 206.990299ms)","trace[1209216263] 'applied index is now lower than readState.Index'  (duration: 189.974µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T13:35:24.276582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.378486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T13:35:24.276672Z","caller":"traceutil/trace.go:171","msg":"trace[2052081510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:341; }","duration":"207.54074ms","start":"2026-03-09T13:35:24.069114Z","end":"2026-03-09T13:35:24.276655Z","steps":["trace[2052081510] 'agreement among raft nodes before linearized reading'  (duration: 207.362476ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T13:35:24.276815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.21947ms","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-09T13:35:24.276914Z","caller":"traceutil/trace.go:171","msg":"trace[1786422903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:341; }","duration":"167.316323ms","start":"2026-03-09T13:35:24.109545Z","end":"2026-03-09T13:35:24.276862Z","steps":["trace[1786422903] 'agreement among raft nodes before linearized reading'  (duration: 167.17534ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T13:35:24.535636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.929708ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4581740625151218194 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:326 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:429 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-09T13:35:24.535792Z","caller":"traceutil/trace.go:171","msg":"trace[1746098390] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:353; }","duration":"255.100057ms","start":"2026-03-09T13:35:24.280671Z","end":"2026-03-09T13:35:24.535771Z","steps":["trace[1746098390] 'read index received'  (duration: 123.546367ms)","trace[1746098390] 'applied index is now lower than readState.Index'  (duration: 131.55209ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T13:35:24.536081Z","caller":"traceutil/trace.go:171","msg":"trace[539313499] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"255.764429ms","start":"2026-03-09T13:35:24.280298Z","end":"2026-03-09T13:35:24.536062Z","steps":["trace[539313499] 'process raft request'  (duration: 123.896844ms)","trace[539313499] 'compare'  (duration: 130.817155ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T13:35:24.536505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.79977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"}
{"level":"info","ts":"2026-03-09T13:35:24.536599Z","caller":"traceutil/trace.go:171","msg":"trace[727351251] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:342; }","duration":"255.930433ms","start":"2026-03-09T13:35:24.280648Z","end":"2026-03-09T13:35:24.536579Z","steps":["trace[727351251] 'agreement among raft nodes before linearized reading'  (duration: 255.633437ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T13:35:24.537087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.098579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"}
{"level":"info","ts":"2026-03-09T13:35:24.537137Z","caller":"traceutil/trace.go:171","msg":"trace[1115837300] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:342; }","duration":"254.17501ms","start":"2026-03-09T13:35:24.28295Z","end":"2026-03-09T13:35:24.537125Z","steps":["trace[1115837300] 'agreement among raft nodes before linearized reading'  (duration: 253.923245ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T13:35:58.359256Z","caller":"traceutil/trace.go:171","msg":"trace[745573990] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"124.199999ms","start":"2026-03-09T13:35:58.235032Z","end":"2026-03-09T13:35:58.359232Z","steps":["trace[745573990] 'read index received'  (duration: 124.143808ms)","trace[745573990] 'applied index is now lower than readState.Index'  (duration: 55.041µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T13:35:58.35939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.343072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T13:35:58.359428Z","caller":"traceutil/trace.go:171","msg":"trace[248026225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"124.439064ms","start":"2026-03-09T13:35:58.234979Z","end":"2026-03-09T13:35:58.359418Z","steps":["trace[248026225] 'agreement among raft nodes before linearized reading'  (duration: 124.355002ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T13:36:55.399107Z","caller":"traceutil/trace.go:171","msg":"trace[262863009] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:930; }","duration":"162.498274ms","start":"2026-03-09T13:36:55.236589Z","end":"2026-03-09T13:36:55.399087Z","steps":["trace[262863009] 'read index received'  (duration: 162.307751ms)","trace[262863009] 'applied index is now lower than readState.Index'  (duration: 189.303µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T13:36:55.399269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.647307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T13:36:55.399292Z","caller":"traceutil/trace.go:171","msg":"trace[1439563707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"162.728529ms","start":"2026-03-09T13:36:55.236556Z","end":"2026-03-09T13:36:55.399285Z","steps":["trace[1439563707] 'agreement among raft nodes before linearized reading'  (duration: 162.612887ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T13:36:55.399577Z","caller":"traceutil/trace.go:171","msg":"trace[1710274431] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"185.688305ms","start":"2026-03-09T13:36:55.213879Z","end":"2026-03-09T13:36:55.399568Z","steps":["trace[1710274431] 'process raft request'  (duration: 185.062874ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T13:37:12.275255Z","caller":"traceutil/trace.go:171","msg":"trace[735609341] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"130.07335ms","start":"2026-03-09T13:37:12.145167Z","end":"2026-03-09T13:37:12.27524Z","steps":["trace[735609341] 'process raft request'  (duration: 129.737634ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T13:37:24.740217Z","caller":"traceutil/trace.go:171","msg":"trace[360765767] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"155.43073ms","start":"2026-03-09T13:37:24.584757Z","end":"2026-03-09T13:37:24.740188Z","steps":["trace[360765767] 'process raft request'  (duration: 155.271357ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T13:37:36.67292Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740625151219840,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-09T13:37:37.173496Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740625151219840,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-09T13:37:37.673666Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740625151219840,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-09T13:37:37.746419Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.650970589s","expected-duration":"1s"}
{"level":"info","ts":"2026-03-09T13:37:37.746834Z","caller":"traceutil/trace.go:171","msg":"trace[257162860] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"1.651452428s","start":"2026-03-09T13:37:36.095359Z","end":"2026-03-09T13:37:37.746812Z","steps":["trace[257162860] 'process raft request'  (duration: 1.651308185s)"],"step_count":1}
{"level":"info","ts":"2026-03-09T13:37:37.746929Z","caller":"traceutil/trace.go:171","msg":"trace[1456758028] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1138; }","duration":"1.574787619s","start":"2026-03-09T13:37:36.172112Z","end":"2026-03-09T13:37:37.7469Z","steps":["trace[1456758028] 'read index received'  (duration: 1.574773989s)","trace[1456758028] 'applied index is now lower than readState.Index'  (duration: 11.5µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T13:37:37.746965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T13:37:36.095344Z","time spent":"1.651544719s","remote":"127.0.0.1:36244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" mod_revision:1074 > success:<request_put:<key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" value_size:432 >> failure:<request_range:<key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" > >"}
{"level":"warn","ts":"2026-03-09T13:37:37.747166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.575076076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"info","ts":"2026-03-09T13:37:37.747204Z","caller":"traceutil/trace.go:171","msg":"trace[775019409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1093; }","duration":"1.575189868s","start":"2026-03-09T13:37:36.172004Z","end":"2026-03-09T13:37:37.747194Z","steps":["trace[775019409] 'agreement among raft nodes before linearized reading'  (duration: 1.575032195s)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T13:37:37.747235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T13:37:36.171953Z","time spent":"1.575275089s","remote":"127.0.0.1:36244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-03-09T13:37:37.747685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.433574729s","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:531"}
{"level":"info","ts":"2026-03-09T13:37:37.747806Z","caller":"traceutil/trace.go:171","msg":"trace[1846959912] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1093; }","duration":"1.433849425s","start":"2026-03-09T13:37:36.313878Z","end":"2026-03-09T13:37:37.747728Z","steps":["trace[1846959912] 'agreement among raft nodes before linearized reading'  (duration: 1.433497988s)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T13:37:37.747876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.372619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"}
{"level":"warn","ts":"2026-03-09T13:37:37.747903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T13:37:36.313854Z","time spent":"1.434035099s","remote":"127.0.0.1:36244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "}
{"level":"info","ts":"2026-03-09T13:37:37.747942Z","caller":"traceutil/trace.go:171","msg":"trace[1887876888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1093; }","duration":"509.473531ms","start":"2026-03-09T13:37:37.23845Z","end":"2026-03-09T13:37:37.747923Z","steps":["trace[1887876888] 'agreement among raft nodes before linearized reading'  (duration: 509.306438ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T13:37:37.747975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T13:37:37.238429Z","time spent":"509.538632ms","remote":"127.0.0.1:36244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-03-09T13:37:37.748095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.512380789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T13:37:37.748182Z","caller":"traceutil/trace.go:171","msg":"trace[1430039162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"1.512496642s","start":"2026-03-09T13:37:36.235662Z","end":"2026-03-09T13:37:37.748159Z","steps":["trace[1430039162] 'agreement among raft nodes before linearized reading'  (duration: 1.512264567s)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T13:37:37.748226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T13:37:36.235622Z","time spent":"1.512596584s","remote":"127.0.0.1:52962","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-09T13:37:37.748313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304398178s","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-09T13:37:37.748407Z","caller":"traceutil/trace.go:171","msg":"trace[1338995487] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1093; }","duration":"1.304549661s","start":"2026-03-09T13:37:36.443839Z","end":"2026-03-09T13:37:37.748389Z","steps":["trace[1338995487] 'agreement among raft nodes before linearized reading'  (duration: 1.303526142s)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T13:37:37.748475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T13:37:36.443818Z","time spent":"1.304645232s","remote":"127.0.0.1:36244","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":"warn","ts":"2026-03-09T13:37:37.747825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.571790072s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-03-09T13:37:37.74909Z","caller":"traceutil/trace.go:171","msg":"trace[1107177144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1093; }","duration":"1.573197229s","start":"2026-03-09T13:37:36.175875Z","end":"2026-03-09T13:37:37.749072Z","steps":["trace[1107177144] 'agreement among raft nodes before linearized reading'  (duration: 1.571452686s)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T13:37:37.749184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T13:37:36.175857Z","time spent":"1.5732673s","remote":"127.0.0.1:36244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-03-09T13:40:04.670778Z","caller":"traceutil/trace.go:171","msg":"trace[801430703] transaction","detail":"{read_only:false; response_revision:1696; number_of_response:1; }","duration":"101.553981ms","start":"2026-03-09T13:40:04.569184Z","end":"2026-03-09T13:40:04.670738Z","steps":["trace[801430703] 'process raft request'  (duration: 70.330502ms)","trace[801430703] 'compare'  (duration: 31.041885ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T13:40:10.699145Z","caller":"traceutil/trace.go:171","msg":"trace[1230440438] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"103.401034ms","start":"2026-03-09T13:40:10.595717Z","end":"2026-03-09T13:40:10.699118Z","steps":["trace[1230440438] 'process raft request'  (duration: 29.327774ms)","trace[1230440438] 'compare'  (duration: 73.738464ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T13:40:10.699423Z","caller":"traceutil/trace.go:171","msg":"trace[682861542] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"102.055779ms","start":"2026-03-09T13:40:10.597359Z","end":"2026-03-09T13:40:10.699414Z","steps":["trace[682861542] 'process raft request'  (duration: 101.55045ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T13:40:19.774463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.324715ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4581740625151221569 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/default/test-pvc.189b2ff7cbd66ecc\" mod_revision:0 > success:<request_put:<key:\"/registry/events/default/test-pvc.189b2ff7cbd66ecc\" value_size:770 lease:4581740625151221567 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-03-09T13:40:19.77459Z","caller":"traceutil/trace.go:171","msg":"trace[1526410328] transaction","detail":"{read_only:false; response_revision:1762; number_of_response:1; }","duration":"132.703137ms","start":"2026-03-09T13:40:19.641876Z","end":"2026-03-09T13:40:19.774579Z","steps":["trace[1526410328] 'process raft request'  (duration: 132.675276ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T13:40:19.775223Z","caller":"traceutil/trace.go:171","msg":"trace[802667433] transaction","detail":"{read_only:false; response_revision:1761; number_of_response:1; }","duration":"132.810169ms","start":"2026-03-09T13:40:19.641872Z","end":"2026-03-09T13:40:19.774682Z","steps":["trace[802667433] 'process raft request'  (duration: 25.213009ms)","trace[802667433] 'compare'  (duration: 107.189142ms)"],"step_count":2}