{"level":"warn","ts":"2026-02-20T15:47:24.05502Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-20T15:47:24.055126Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-02-20T15:47:24.055871Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-20T15:47:24.055896Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-02-20T15:47:24.055919Z","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-02-20T15:47:24.056822Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-02-20T15:47:24.056913Z","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.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-02-20T15:47:24.061982Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.760716ms"} {"level":"info","ts":"2026-02-20T15:47:24.068181Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-02-20T15:47:24.068265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-02-20T15:47:24.068283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-02-20T15:47:24.068295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-20T15:47:24.068307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-02-20T15:47:24.06842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-02-20T15:47:24.076586Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-20T15:47:24.078897Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-20T15:47:24.080466Z","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-02-20T15:47:24.083345Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-20T15:47:24.083662Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-20T15:47:24.083941Z","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-02-20T15:47:24.084022Z","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-02-20T15:47:24.084034Z","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-02-20T15:47:24.088607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-02-20T15:47:24.090459Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-02-20T15:47:24.09222Z","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-02-20T15:47:24.092372Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-02-20T15:47:24.092984Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-02-20T15:47:24.092595Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-20T15:47:24.09264Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-20T15:47:25.069269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-02-20T15:47:25.069367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-20T15:47:25.069417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-02-20T15:47:25.069439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-02-20T15:47:25.069458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-02-20T15:47:25.069504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-02-20T15:47:25.069526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-02-20T15:47:25.070658Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T15:47:25.071568Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-02-20T15:47:25.071593Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T15:47:25.071584Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T15:47:25.071965Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-20T15:47:25.072036Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-20T15:47:25.072199Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T15:47:25.072341Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T15:47:25.072375Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T15:47:25.073178Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-02-20T15:47:25.073897Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-20T15:47:30.368086Z","caller":"traceutil/trace.go:171","msg":"trace[539200846] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"149.039641ms","start":"2026-02-20T15:47:30.219001Z","end":"2026-02-20T15:47:30.368041Z","steps":["trace[539200846] 'process raft request' (duration: 148.682877ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:30.368199Z","caller":"traceutil/trace.go:171","msg":"trace[1576079418] transaction","detail":"{read_only:false; number_of_response:0; response_revision:241; }","duration":"148.221093ms","start":"2026-02-20T15:47:30.219938Z","end":"2026-02-20T15:47:30.368159Z","steps":["trace[1576079418] 'process raft request' (duration: 148.00184ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:30.368242Z","caller":"traceutil/trace.go:171","msg":"trace[1524685311] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"135.452632ms","start":"2026-02-20T15:47:30.232743Z","end":"2026-02-20T15:47:30.368195Z","steps":["trace[1524685311] 'process raft request' (duration: 135.24443ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:30.368597Z","caller":"traceutil/trace.go:171","msg":"trace[508976733] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"133.966575ms","start":"2026-02-20T15:47:30.234616Z","end":"2026-02-20T15:47:30.368582Z","steps":["trace[508976733] 'process raft request' (duration: 133.50671ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:30.368804Z","caller":"traceutil/trace.go:171","msg":"trace[1699710387] transaction","detail":"{read_only:false; number_of_response:0; response_revision:243; }","duration":"133.54455ms","start":"2026-02-20T15:47:30.235248Z","end":"2026-02-20T15:47:30.368792Z","steps":["trace[1699710387] 'process raft request' (duration: 133.288848ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:30.368945Z","caller":"traceutil/trace.go:171","msg":"trace[1436700040] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"133.336799ms","start":"2026-02-20T15:47:30.235589Z","end":"2026-02-20T15:47:30.368926Z","steps":["trace[1436700040] 'process raft request' (duration: 133.013595ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:30.36895Z","caller":"traceutil/trace.go:171","msg":"trace[345528469] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:246; }","duration":"109.566226ms","start":"2026-02-20T15:47:30.259369Z","end":"2026-02-20T15:47:30.368935Z","steps":["trace[345528469] 'read index received' (duration: 108.346673ms)","trace[345528469] 'applied index is now lower than readState.Index' (duration: 1.218663ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:47:30.369104Z","caller":"traceutil/trace.go:171","msg":"trace[964066912] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"131.520659ms","start":"2026-02-20T15:47:30.237549Z","end":"2026-02-20T15:47:30.369069Z","steps":["trace[964066912] 'process raft request' (duration: 131.216505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:47:30.369236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.84447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-20T15:47:30.369325Z","caller":"traceutil/trace.go:171","msg":"trace[76904743] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:245; }","duration":"110.000601ms","start":"2026-02-20T15:47:30.259309Z","end":"2026-02-20T15:47:30.36931Z","steps":["trace[76904743] 'agreement among raft nodes before linearized reading' (duration: 109.671878ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:30.395519Z","caller":"traceutil/trace.go:171","msg":"trace[2083190597] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"133.541321ms","start":"2026-02-20T15:47:30.261947Z","end":"2026-02-20T15:47:30.395489Z","steps":["trace[2083190597] 'process raft request' (duration: 133.399619ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:30.582383Z","caller":"traceutil/trace.go:171","msg":"trace[1376096485] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:260; }","duration":"127.971371ms","start":"2026-02-20T15:47:30.454385Z","end":"2026-02-20T15:47:30.582356Z","steps":["trace[1376096485] 'read index received' (duration: 48.01009ms)","trace[1376096485] 'applied index is now lower than readState.Index' (duration: 79.957151ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:47:30.582584Z","caller":"traceutil/trace.go:171","msg":"trace[1828726037] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"161.882664ms","start":"2026-02-20T15:47:30.420678Z","end":"2026-02-20T15:47:30.582561Z","steps":["trace[1828726037] 'process raft request' (duration: 81.700591ms)","trace[1828726037] 'compare' (duration: 79.747519ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:47:30.582696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.819987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-20T15:47:30.582733Z","caller":"traceutil/trace.go:171","msg":"trace[526322412] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:253; }","duration":"125.870467ms","start":"2026-02-20T15:47:30.456851Z","end":"2026-02-20T15:47:30.582722Z","steps":["trace[526322412] 'agreement among raft nodes before linearized reading' (duration: 125.778436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:47:30.582595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.748829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:47:30.582994Z","caller":"traceutil/trace.go:171","msg":"trace[93423936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:253; }","duration":"139.209895ms","start":"2026-02-20T15:47:30.44376Z","end":"2026-02-20T15:47:30.58297Z","steps":["trace[93423936] 'agreement among raft nodes before linearized reading' (duration: 138.73158ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:31.255766Z","caller":"traceutil/trace.go:171","msg":"trace[2112609105] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"110.478916ms","start":"2026-02-20T15:47:31.145266Z","end":"2026-02-20T15:47:31.255745Z","steps":["trace[2112609105] 'process raft request' (duration: 110.161073ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:31.256049Z","caller":"traceutil/trace.go:171","msg":"trace[1001863036] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"110.716808ms","start":"2026-02-20T15:47:31.145323Z","end":"2026-02-20T15:47:31.25604Z","steps":["trace[1001863036] 'process raft request' (duration: 110.218743ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:31.256169Z","caller":"traceutil/trace.go:171","msg":"trace[332188402] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"110.850991ms","start":"2026-02-20T15:47:31.145308Z","end":"2026-02-20T15:47:31.256159Z","steps":["trace[332188402] 'process raft request' (duration: 110.260664ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:31.406368Z","caller":"traceutil/trace.go:171","msg":"trace[1699957264] linearizableReadLoop","detail":"{readStateIndex:281; appliedIndex:280; }","duration":"130.032639ms","start":"2026-02-20T15:47:31.276303Z","end":"2026-02-20T15:47:31.406336Z","steps":["trace[1699957264] 'read index received' (duration: 81.450559ms)","trace[1699957264] 'applied index is now lower than readState.Index' (duration: 48.58074ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:47:31.406559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.213251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:47:31.406591Z","caller":"traceutil/trace.go:171","msg":"trace[810273334] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:269; }","duration":"130.301053ms","start":"2026-02-20T15:47:31.276279Z","end":"2026-02-20T15:47:31.40658Z","steps":["trace[810273334] 'agreement among raft nodes before linearized reading' (duration: 130.198132ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:31.40688Z","caller":"traceutil/trace.go:171","msg":"trace[1084329961] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"135.087005ms","start":"2026-02-20T15:47:31.271722Z","end":"2026-02-20T15:47:31.406809Z","steps":["trace[1084329961] 'process raft request' (duration: 86.0979ms)","trace[1084329961] 'compare' (duration: 48.370168ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:47:31.642358Z","caller":"traceutil/trace.go:171","msg":"trace[1538221982] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"139.28842ms","start":"2026-02-20T15:47:31.503036Z","end":"2026-02-20T15:47:31.642324Z","steps":["trace[1538221982] 'process raft request' (duration: 139.060857ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:31.658687Z","caller":"traceutil/trace.go:171","msg":"trace[15352659] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"154.588737ms","start":"2026-02-20T15:47:31.504047Z","end":"2026-02-20T15:47:31.658636Z","steps":["trace[15352659] 'process raft request' (duration: 154.413205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:47:33.304387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.243382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-20T15:47:33.304492Z","caller":"traceutil/trace.go:171","msg":"trace[187391271] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:288; }","duration":"111.400155ms","start":"2026-02-20T15:47:33.19307Z","end":"2026-02-20T15:47:33.30447Z","steps":["trace[187391271] 'range keys from in-memory index tree' (duration: 111.134082ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:33.446483Z","caller":"traceutil/trace.go:171","msg":"trace[175462598] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"137.853058ms","start":"2026-02-20T15:47:33.308604Z","end":"2026-02-20T15:47:33.446457Z","steps":["trace[175462598] 'process raft request' (duration: 137.694696ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:34.572612Z","caller":"traceutil/trace.go:171","msg":"trace[1487020738] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"119.743502ms","start":"2026-02-20T15:47:34.452822Z","end":"2026-02-20T15:47:34.572566Z","steps":["trace[1487020738] 'process raft request' (duration: 50.455736ms)","trace[1487020738] 'compare' (duration: 69.118314ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:47:34.710816Z","caller":"traceutil/trace.go:171","msg":"trace[1896829920] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"109.017659ms","start":"2026-02-20T15:47:34.601772Z","end":"2026-02-20T15:47:34.710789Z","steps":["trace[1896829920] 'process raft request' (duration: 88.094506ms)","trace[1896829920] 'compare' (duration: 20.581619ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:47:35.199135Z","caller":"traceutil/trace.go:171","msg":"trace[163876436] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"107.707916ms","start":"2026-02-20T15:47:35.091403Z","end":"2026-02-20T15:47:35.199111Z","steps":["trace[163876436] 'process raft request' (duration: 78.985193ms)","trace[163876436] 'compare' (duration: 28.45155ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:47:35.199465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.955008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-20T15:47:35.199543Z","caller":"traceutil/trace.go:171","msg":"trace[1675987872] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:314; }","duration":"107.093399ms","start":"2026-02-20T15:47:35.092431Z","end":"2026-02-20T15:47:35.199524Z","steps":["trace[1675987872] 'agreement among raft nodes before linearized reading' (duration: 106.865966ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:35.199028Z","caller":"traceutil/trace.go:171","msg":"trace[1609368947] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:325; }","duration":"106.541663ms","start":"2026-02-20T15:47:35.092458Z","end":"2026-02-20T15:47:35.198999Z","steps":["trace[1609368947] 'read index received' (duration: 77.947902ms)","trace[1609368947] 'applied index is now lower than readState.Index' (duration: 28.592501ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:47:35.230539Z","caller":"traceutil/trace.go:171","msg":"trace[969817166] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"134.626878ms","start":"2026-02-20T15:47:35.095892Z","end":"2026-02-20T15:47:35.230519Z","steps":["trace[969817166] 'process raft request' (duration: 134.479527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:47:35.951007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.915937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T15:47:35.951289Z","caller":"traceutil/trace.go:171","msg":"trace[1403948237] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:340; }","duration":"584.187498ms","start":"2026-02-20T15:47:35.367086Z","end":"2026-02-20T15:47:35.951273Z","steps":["trace[1403948237] 'read index received' (duration: 143.339151ms)","trace[1403948237] 'applied index is now lower than readState.Index' (duration: 440.847367ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:47:35.951367Z","caller":"traceutil/trace.go:171","msg":"trace[1669286775] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"580.982965ms","start":"2026-02-20T15:47:35.370329Z","end":"2026-02-20T15:47:35.951312Z","steps":["trace[1669286775] 'process raft request' (duration: 580.815103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:47:35.951468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.36174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2879"} {"level":"info","ts":"2026-02-20T15:47:35.951537Z","caller":"traceutil/trace.go:171","msg":"trace[1689104426] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:330; }","duration":"584.477692ms","start":"2026-02-20T15:47:35.367045Z","end":"2026-02-20T15:47:35.951522Z","steps":["trace[1689104426] 'agreement among raft nodes before linearized reading' (duration: 584.362251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:47:35.951574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:47:35.367027Z","time spent":"584.536852ms","remote":"127.0.0.1:57628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2902,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-02-20T15:47:35.951817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.619136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-20T15:47:35.951867Z","caller":"traceutil/trace.go:171","msg":"trace[1304149113] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"586.071788ms","start":"2026-02-20T15:47:35.365122Z","end":"2026-02-20T15:47:35.951193Z","steps":["trace[1304149113] 'process raft request' (duration: 145.290912ms)","trace[1304149113] 'compare' (duration: 439.607204ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:47:35.951874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:47:35.370314Z","time spent":"581.133926ms","remote":"127.0.0.1:57428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4402,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:47:35.95203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:47:35.365103Z","time spent":"586.835306ms","remote":"127.0.0.1:57428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3505,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T15:47:35.952008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.101165ms","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-02-20T15:47:35.951913Z","caller":"traceutil/trace.go:171","msg":"trace[1704641201] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:330; }","duration":"388.764908ms","start":"2026-02-20T15:47:35.563133Z","end":"2026-02-20T15:47:35.951898Z","steps":["trace[1704641201] 'agreement among raft nodes before linearized reading' (duration: 388.495715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:47:35.952274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:47:35.563109Z","time spent":"389.148932ms","remote":"127.0.0.1:57442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":208,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-02-20T15:47:35.952123Z","caller":"traceutil/trace.go:171","msg":"trace[1021059471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:330; }","duration":"101.285097ms","start":"2026-02-20T15:47:35.850807Z","end":"2026-02-20T15:47:35.952092Z","steps":["trace[1021059471] 'agreement among raft nodes before linearized reading' (duration: 101.015284ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:36.179696Z","caller":"traceutil/trace.go:171","msg":"trace[1795458583] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"221.213556ms","start":"2026-02-20T15:47:35.958433Z","end":"2026-02-20T15:47:36.179647Z","steps":["trace[1795458583] 'process raft request' (duration: 144.11174ms)","trace[1795458583] 'compare' (duration: 76.773653ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:47:36.179774Z","caller":"traceutil/trace.go:171","msg":"trace[1398949848] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:342; }","duration":"193.186381ms","start":"2026-02-20T15:47:35.986555Z","end":"2026-02-20T15:47:36.179741Z","steps":["trace[1398949848] 'read index received' (duration: 14.713335ms)","trace[1398949848] 'applied index is now lower than readState.Index' (duration: 178.471516ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:47:36.179964Z","caller":"traceutil/trace.go:171","msg":"trace[1878731091] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"220.517459ms","start":"2026-02-20T15:47:35.959415Z","end":"2026-02-20T15:47:36.179933Z","steps":["trace[1878731091] 'process raft request' (duration: 220.203716ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:36.180037Z","caller":"traceutil/trace.go:171","msg":"trace[1478343292] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"214.384664ms","start":"2026-02-20T15:47:35.965637Z","end":"2026-02-20T15:47:36.180022Z","steps":["trace[1478343292] 'process raft request' (duration: 214.047691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:47:36.17998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.399223ms","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-02-20T15:47:36.180233Z","caller":"traceutil/trace.go:171","msg":"trace[1808879560] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:335; }","duration":"193.734616ms","start":"2026-02-20T15:47:35.986489Z","end":"2026-02-20T15:47:36.180224Z","steps":["trace[1808879560] 'agreement among raft nodes before linearized reading' (duration: 193.370452ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:36.180279Z","caller":"traceutil/trace.go:171","msg":"trace[922883951] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"220.992344ms","start":"2026-02-20T15:47:35.959251Z","end":"2026-02-20T15:47:36.180243Z","steps":["trace[922883951] 'process raft request' (duration: 220.304457ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:36.364329Z","caller":"traceutil/trace.go:171","msg":"trace[80042186] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:353; }","duration":"118.886392ms","start":"2026-02-20T15:47:36.245415Z","end":"2026-02-20T15:47:36.364302Z","steps":["trace[80042186] 'read index received' (duration: 80.965636ms)","trace[80042186] 'applied index is now lower than readState.Index' (duration: 37.919766ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:47:36.364348Z","caller":"traceutil/trace.go:171","msg":"trace[1681192938] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"120.815742ms","start":"2026-02-20T15:47:36.2435Z","end":"2026-02-20T15:47:36.364316Z","steps":["trace[1681192938] 'process raft request' (duration: 82.727925ms)","trace[1681192938] 'compare' (duration: 37.837665ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:47:36.364484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.037764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3797"} {"level":"info","ts":"2026-02-20T15:47:36.364531Z","caller":"traceutil/trace.go:171","msg":"trace[660223611] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:342; }","duration":"119.115224ms","start":"2026-02-20T15:47:36.245402Z","end":"2026-02-20T15:47:36.364517Z","steps":["trace[660223611] 'agreement among raft nodes before linearized reading' (duration: 119.004633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:47:39.135858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.849049ms","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-02-20T15:47:39.135963Z","caller":"traceutil/trace.go:171","msg":"trace[1484975021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:358; }","duration":"176.031591ms","start":"2026-02-20T15:47:38.959908Z","end":"2026-02-20T15:47:39.13594Z","steps":["trace[1484975021] 'range keys from in-memory index tree' (duration: 175.700867ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:44.551051Z","caller":"traceutil/trace.go:171","msg":"trace[483292603] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"244.343577ms","start":"2026-02-20T15:47:44.306684Z","end":"2026-02-20T15:47:44.551027Z","steps":["trace[483292603] 'process raft request' (duration: 244.200996ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:44.551641Z","caller":"traceutil/trace.go:171","msg":"trace[1211328253] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:391; }","duration":"219.26353ms","start":"2026-02-20T15:47:44.332363Z","end":"2026-02-20T15:47:44.551627Z","steps":["trace[1211328253] 'read index received' (duration: 219.25765ms)","trace[1211328253] 'applied index is now lower than readState.Index' (duration: 4.76µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:47:44.55172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.343071ms","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-02-20T15:47:44.551752Z","caller":"traceutil/trace.go:171","msg":"trace[130341440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:376; }","duration":"219.420052ms","start":"2026-02-20T15:47:44.33232Z","end":"2026-02-20T15:47:44.55174Z","steps":["trace[130341440] 'agreement among raft nodes before linearized reading' (duration: 219.353471ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:44.736051Z","caller":"traceutil/trace.go:171","msg":"trace[592465567] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:391; }","duration":"173.208816ms","start":"2026-02-20T15:47:44.562797Z","end":"2026-02-20T15:47:44.736006Z","steps":["trace[592465567] 'read index received' (duration: 74.347312ms)","trace[592465567] 'applied index is now lower than readState.Index' (duration: 98.860044ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:47:44.736188Z","caller":"traceutil/trace.go:171","msg":"trace[1679005864] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"173.614991ms","start":"2026-02-20T15:47:44.562529Z","end":"2026-02-20T15:47:44.736144Z","steps":["trace[1679005864] 'process raft request' (duration: 74.684986ms)","trace[1679005864] 'compare' (duration: 98.563441ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:47:44.736294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.417278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:47:44.736362Z","caller":"traceutil/trace.go:171","msg":"trace[1610394143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"173.561769ms","start":"2026-02-20T15:47:44.562774Z","end":"2026-02-20T15:47:44.736336Z","steps":["trace[1610394143] 'agreement among raft nodes before linearized reading' (duration: 173.394898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:47:51.081869Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125952867438110647,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T15:47:51.190692Z","caller":"traceutil/trace.go:171","msg":"trace[1552754954] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"609.209292ms","start":"2026-02-20T15:47:50.581421Z","end":"2026-02-20T15:47:51.19063Z","steps":["trace[1552754954] 'read index received' (duration: 608.99762ms)","trace[1552754954] 'applied index is now lower than readState.Index' (duration: 210.102µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:47:51.19077Z","caller":"traceutil/trace.go:171","msg":"trace[949650603] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"672.556953ms","start":"2026-02-20T15:47:50.518172Z","end":"2026-02-20T15:47:51.190729Z","steps":["trace[949650603] 'process raft request' (duration: 672.25986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:47:51.190977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:47:50.518142Z","time spent":"672.711645ms","remote":"127.0.0.1:57490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:47:51.190972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.511924ms","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-02-20T15:47:51.191093Z","caller":"traceutil/trace.go:171","msg":"trace[826216553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:419; }","duration":"609.711537ms","start":"2026-02-20T15:47:50.58135Z","end":"2026-02-20T15:47:51.191061Z","steps":["trace[826216553] 'agreement among raft nodes before linearized reading' (duration: 609.466885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:47:51.19115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:47:50.581318Z","time spent":"609.823328ms","remote":"127.0.0.1:57490","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-02-20T15:47:51.191297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.382678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-20T15:47:51.191321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.436199ms","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-02-20T15:47:51.191378Z","caller":"traceutil/trace.go:171","msg":"trace[1590610297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:419; }","duration":"346.549799ms","start":"2026-02-20T15:47:50.84481Z","end":"2026-02-20T15:47:51.191359Z","steps":["trace[1590610297] 'agreement among raft nodes before linearized reading' (duration: 346.275486ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:51.191429Z","caller":"traceutil/trace.go:171","msg":"trace[946095460] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:419; }","duration":"384.60527ms","start":"2026-02-20T15:47:50.8068Z","end":"2026-02-20T15:47:51.191405Z","steps":["trace[946095460] 'agreement among raft nodes before linearized reading' (duration: 384.378268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:47:51.191434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:47:50.844778Z","time spent":"346.64477ms","remote":"127.0.0.1:57490","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-02-20T15:47:51.191481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:47:50.806769Z","time spent":"384.702901ms","remote":"127.0.0.1:57490","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-02-20T15:47:51.627317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.179274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T15:47:51.627525Z","caller":"traceutil/trace.go:171","msg":"trace[894126231] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:436; }","duration":"413.984267ms","start":"2026-02-20T15:47:51.213524Z","end":"2026-02-20T15:47:51.627508Z","steps":["trace[894126231] 'read index received' (duration: 61.617241ms)","trace[894126231] 'applied index is now lower than readState.Index' (duration: 352.366046ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:47:51.627515Z","caller":"traceutil/trace.go:171","msg":"trace[1346499865] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"430.476072ms","start":"2026-02-20T15:47:51.197008Z","end":"2026-02-20T15:47:51.627484Z","steps":["trace[1346499865] 'process raft request' (duration: 78.030036ms)","trace[1346499865] 'compare' (duration: 352.067693ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:47:51.627615Z","caller":"traceutil/trace.go:171","msg":"trace[1874565410] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"430.1125ms","start":"2026-02-20T15:47:51.197493Z","end":"2026-02-20T15:47:51.627606Z","steps":["trace[1874565410] 'process raft request' (duration: 429.979558ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:47:51.627613Z","caller":"traceutil/trace.go:171","msg":"trace[973558530] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"430.27267ms","start":"2026-02-20T15:47:51.19732Z","end":"2026-02-20T15:47:51.627593Z","steps":["trace[973558530] 'process raft request' (duration: 430.098689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:47:51.627682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:47:51.196981Z","time spent":"430.603593ms","remote":"127.0.0.1:57490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:47:51.627783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.253549ms","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-02-20T15:47:51.627846Z","caller":"traceutil/trace.go:171","msg":"trace[412889401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:422; }","duration":"414.32197ms","start":"2026-02-20T15:47:51.213494Z","end":"2026-02-20T15:47:51.627816Z","steps":["trace[412889401] 'agreement among raft nodes before linearized reading' (duration: 414.243359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:47:51.627879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:47:51.213484Z","time spent":"414.38802ms","remote":"127.0.0.1:57490","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-02-20T15:47:51.627682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:47:51.197455Z","time spent":"430.19201ms","remote":"127.0.0.1:57490","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-02-20T15:47:51.627802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:47:51.197289Z","time spent":"430.431412ms","remote":"127.0.0.1:57490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:48:02.422838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.849144ms","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-02-20T15:48:02.422969Z","caller":"traceutil/trace.go:171","msg":"trace[505376245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:478; }","duration":"727.032565ms","start":"2026-02-20T15:48:01.695909Z","end":"2026-02-20T15:48:02.422941Z","steps":["trace[505376245] 'range keys from in-memory index tree' (duration: 726.695583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:02.423021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:48:01.695881Z","time spent":"727.128376ms","remote":"127.0.0.1:57490","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-02-20T15:48:02.423339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.386959ms","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-02-20T15:48:02.423374Z","caller":"traceutil/trace.go:171","msg":"trace[1863959595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:478; }","duration":"727.446529ms","start":"2026-02-20T15:48:01.695915Z","end":"2026-02-20T15:48:02.423362Z","steps":["trace[1863959595] 'range keys from in-memory index tree' (duration: 727.285158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:02.423373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.160556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-20T15:48:02.423397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:48:01.695908Z","time spent":"727.482949ms","remote":"127.0.0.1:57490","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":"info","ts":"2026-02-20T15:48:02.423411Z","caller":"traceutil/trace.go:171","msg":"trace[111217069] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:478; }","duration":"728.242936ms","start":"2026-02-20T15:48:01.695153Z","end":"2026-02-20T15:48:02.423396Z","steps":["trace[111217069] 'range keys from in-memory index tree' (duration: 727.922773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:02.423443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:48:01.695125Z","time spent":"728.309947ms","remote":"127.0.0.1:57490","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-02-20T15:48:02.42348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.894863ms","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-02-20T15:48:02.423555Z","caller":"traceutil/trace.go:171","msg":"trace[551933152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:478; }","duration":"727.997725ms","start":"2026-02-20T15:48:01.695537Z","end":"2026-02-20T15:48:02.423534Z","steps":["trace[551933152] 'range keys from in-memory index tree' (duration: 727.811542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:02.423591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:48:01.695528Z","time spent":"728.057705ms","remote":"127.0.0.1:57490","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":"info","ts":"2026-02-20T15:48:02.62902Z","caller":"traceutil/trace.go:171","msg":"trace[1332412248] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"199.193792ms","start":"2026-02-20T15:48:02.429788Z","end":"2026-02-20T15:48:02.628982Z","steps":["trace[1332412248] 'process raft request' (duration: 198.287234ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:48:02.629136Z","caller":"traceutil/trace.go:171","msg":"trace[1418397378] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"197.338196ms","start":"2026-02-20T15:48:02.431778Z","end":"2026-02-20T15:48:02.629116Z","steps":["trace[1418397378] 'process raft request' (duration: 196.928932ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:48:02.629186Z","caller":"traceutil/trace.go:171","msg":"trace[291842150] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"197.258885ms","start":"2026-02-20T15:48:02.43189Z","end":"2026-02-20T15:48:02.629149Z","steps":["trace[291842150] 'process raft request' (duration: 196.871412ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:48:07.811656Z","caller":"traceutil/trace.go:171","msg":"trace[1211278905] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"544.188051ms","start":"2026-02-20T15:48:07.267443Z","end":"2026-02-20T15:48:07.811631Z","steps":["trace[1211278905] 'process raft request' (duration: 544.005704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:07.811812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:48:07.267425Z","time spent":"544.306734ms","remote":"127.0.0.1:57428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:48:07.850172Z","caller":"traceutil/trace.go:171","msg":"trace[962694921] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"294.813649ms","start":"2026-02-20T15:48:07.555329Z","end":"2026-02-20T15:48:07.850143Z","steps":["trace[962694921] 'read index received' (duration: 256.869948ms)","trace[962694921] 'applied index is now lower than readState.Index' (duration: 37.942501ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:48:07.850277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.928503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:48:07.850295Z","caller":"traceutil/trace.go:171","msg":"trace[40459817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"295.015756ms","start":"2026-02-20T15:48:07.555275Z","end":"2026-02-20T15:48:07.850291Z","steps":["trace[40459817] 'agreement among raft nodes before linearized reading' (duration: 294.956674ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:48:07.850377Z","caller":"traceutil/trace.go:171","msg":"trace[1075775404] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"437.521772ms","start":"2026-02-20T15:48:07.412834Z","end":"2026-02-20T15:48:07.850356Z","steps":["trace[1075775404] 'process raft request' (duration: 437.19914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:07.850501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:48:07.412808Z","time spent":"437.627145ms","remote":"127.0.0.1:57490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:48:08.984229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125952867438110856,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T15:48:09.017837Z","caller":"traceutil/trace.go:171","msg":"trace[1052573476] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"534.591169ms","start":"2026-02-20T15:48:08.483222Z","end":"2026-02-20T15:48:09.017813Z","steps":["trace[1052573476] 'read index received' (duration: 534.226426ms)","trace[1052573476] 'applied index is now lower than readState.Index' (duration: 363.642µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:48:09.017958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:48:08.319853Z","time spent":"698.100928ms","remote":"127.0.0.1:57274","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-20T15:48:09.018613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.168458ms","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-02-20T15:48:09.018794Z","caller":"traceutil/trace.go:171","msg":"trace[148654965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"535.594544ms","start":"2026-02-20T15:48:08.483169Z","end":"2026-02-20T15:48:09.018763Z","steps":["trace[148654965] 'agreement among raft nodes before linearized reading' (duration: 535.032754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:09.01895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:48:08.483145Z","time spent":"535.786681ms","remote":"127.0.0.1:57490","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-02-20T15:48:09.019325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.301138ms","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-02-20T15:48:09.019399Z","caller":"traceutil/trace.go:171","msg":"trace[91950555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"360.391811ms","start":"2026-02-20T15:48:08.65899Z","end":"2026-02-20T15:48:09.019382Z","steps":["trace[91950555] 'agreement among raft nodes before linearized reading' (duration: 360.143413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:09.019437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:48:08.65895Z","time spent":"360.478024ms","remote":"127.0.0.1:57490","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-02-20T15:48:09.019413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.334669ms","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-02-20T15:48:09.019497Z","caller":"traceutil/trace.go:171","msg":"trace[236684944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"360.474484ms","start":"2026-02-20T15:48:08.658999Z","end":"2026-02-20T15:48:09.019473Z","steps":["trace[236684944] 'agreement among raft nodes before linearized reading' (duration: 360.123412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:09.019456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.407362ms","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-02-20T15:48:09.019544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:48:08.658973Z","time spent":"360.562418ms","remote":"127.0.0.1:57490","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-02-20T15:48:09.019579Z","caller":"traceutil/trace.go:171","msg":"trace[1740660716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"360.597728ms","start":"2026-02-20T15:48:08.65895Z","end":"2026-02-20T15:48:09.019548Z","steps":["trace[1740660716] 'agreement among raft nodes before linearized reading' (duration: 360.166323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:09.019654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:48:08.658932Z","time spent":"360.705182ms","remote":"127.0.0.1:57490","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":"info","ts":"2026-02-20T15:48:09.154092Z","caller":"traceutil/trace.go:171","msg":"trace[779596384] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"129.448921ms","start":"2026-02-20T15:48:09.024601Z","end":"2026-02-20T15:48:09.15405Z","steps":["trace[779596384] 'process raft request' (duration: 81.174279ms)","trace[779596384] 'compare' (duration: 47.992412ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:48:09.154139Z","caller":"traceutil/trace.go:171","msg":"trace[739282683] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"125.583026ms","start":"2026-02-20T15:48:09.02847Z","end":"2026-02-20T15:48:09.154053Z","steps":["trace[739282683] 'process raft request' (duration: 125.478402ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:48:09.154527Z","caller":"traceutil/trace.go:171","msg":"trace[479283931] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"125.934008ms","start":"2026-02-20T15:48:09.028556Z","end":"2026-02-20T15:48:09.15449Z","steps":["trace[479283931] 'process raft request' (duration: 125.448641ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:48:09.154581Z","caller":"traceutil/trace.go:171","msg":"trace[1580816748] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"125.906318ms","start":"2026-02-20T15:48:09.028654Z","end":"2026-02-20T15:48:09.15456Z","steps":["trace[1580816748] 'process raft request' (duration: 125.67539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:11.88599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.953744ms","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-02-20T15:48:11.886147Z","caller":"traceutil/trace.go:171","msg":"trace[377463731] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:522; }","duration":"727.188382ms","start":"2026-02-20T15:48:11.15893Z","end":"2026-02-20T15:48:11.886118Z","steps":["trace[377463731] 'range keys from in-memory index tree' (duration: 726.801759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:11.886204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:48:11.158905Z","time spent":"727.287075ms","remote":"127.0.0.1:57490","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-02-20T15:48:11.886188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.139951ms","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-02-20T15:48:11.886266Z","caller":"traceutil/trace.go:171","msg":"trace[1406785527] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:522; }","duration":"727.284065ms","start":"2026-02-20T15:48:11.158962Z","end":"2026-02-20T15:48:11.886246Z","steps":["trace[1406785527] 'range keys from in-memory index tree' (duration: 727.050927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:11.886299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:48:11.15893Z","time spent":"727.362598ms","remote":"127.0.0.1:57490","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-02-20T15:48:11.886421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.124879ms","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-02-20T15:48:11.886355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.636997411s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:48:11.886443Z","caller":"traceutil/trace.go:171","msg":"trace[1129114735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:522; }","duration":"727.154031ms","start":"2026-02-20T15:48:11.159281Z","end":"2026-02-20T15:48:11.886435Z","steps":["trace[1129114735] 'range keys from in-memory index tree' (duration: 727.066698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:11.886461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:48:11.159274Z","time spent":"727.182392ms","remote":"127.0.0.1:57490","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-02-20T15:48:11.88647Z","caller":"traceutil/trace.go:171","msg":"trace[1640874720] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:522; }","duration":"1.637121975s","start":"2026-02-20T15:48:10.249326Z","end":"2026-02-20T15:48:11.886448Z","steps":["trace[1640874720] 'range keys from in-memory index tree' (duration: 1.636977001s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:11.88652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.788484ms","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-02-20T15:48:11.886537Z","caller":"traceutil/trace.go:171","msg":"trace[1968946551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"720.825795ms","start":"2026-02-20T15:48:11.165706Z","end":"2026-02-20T15:48:11.886531Z","steps":["trace[1968946551] 'range keys from in-memory index tree' (duration: 720.6771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:11.886552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:48:11.16569Z","time spent":"720.858096ms","remote":"127.0.0.1:57490","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-02-20T15:48:11.88679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.330202087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:48:11.886805Z","caller":"traceutil/trace.go:171","msg":"trace[2063292140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"2.330217877s","start":"2026-02-20T15:48:09.556582Z","end":"2026-02-20T15:48:11.8868Z","steps":["trace[2063292140] 'range keys from in-memory index tree' (duration: 2.330089233s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:11.886818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:48:09.556485Z","time spent":"2.330329692s","remote":"127.0.0.1:57240","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-02-20T15:48:11.886921Z","caller":"traceutil/trace.go:171","msg":"trace[1189200403] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"329.433076ms","start":"2026-02-20T15:48:11.557468Z","end":"2026-02-20T15:48:11.886901Z","steps":["trace[1189200403] 'read index received' (duration: 300.502729ms)","trace[1189200403] 'applied index is now lower than readState.Index' (duration: 28.929017ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:48:11.886994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.529489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:48:11.886976Z","caller":"traceutil/trace.go:171","msg":"trace[225225443] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"556.110167ms","start":"2026-02-20T15:48:11.330829Z","end":"2026-02-20T15:48:11.886939Z","steps":["trace[225225443] 'process raft request' (duration: 527.202061ms)","trace[225225443] 'compare' (duration: 28.272084ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:48:11.887028Z","caller":"traceutil/trace.go:171","msg":"trace[755195639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"329.591761ms","start":"2026-02-20T15:48:11.557425Z","end":"2026-02-20T15:48:11.887016Z","steps":["trace[755195639] 'agreement among raft nodes before linearized reading' (duration: 329.533569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:11.887063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:48:11.557406Z","time spent":"329.651203ms","remote":"127.0.0.1:57244","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-02-20T15:48:11.887109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:48:11.330804Z","time spent":"556.229661ms","remote":"127.0.0.1:57490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:48:16.684655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.199479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:48:16.684754Z","caller":"traceutil/trace.go:171","msg":"trace[354275573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"128.361953ms","start":"2026-02-20T15:48:16.556369Z","end":"2026-02-20T15:48:16.684731Z","steps":["trace[354275573] 'range keys from in-memory index tree' (duration: 128.099205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:16.684948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.504673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-20T15:48:16.685042Z","caller":"traceutil/trace.go:171","msg":"trace[101358331] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:589; }","duration":"194.642078ms","start":"2026-02-20T15:48:16.49037Z","end":"2026-02-20T15:48:16.685012Z","steps":["trace[101358331] 'range keys from in-memory index tree' (duration: 194.220964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:24.212679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.404543ms","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-02-20T15:48:24.212796Z","caller":"traceutil/trace.go:171","msg":"trace[1086390651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"110.532536ms","start":"2026-02-20T15:48:24.102238Z","end":"2026-02-20T15:48:24.212771Z","steps":["trace[1086390651] 'agreement among raft nodes before linearized reading' (duration: 110.274279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:48:24.213105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.683711ms","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-02-20T15:48:24.213145Z","caller":"traceutil/trace.go:171","msg":"trace[794348700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"109.741602ms","start":"2026-02-20T15:48:24.103393Z","end":"2026-02-20T15:48:24.213135Z","steps":["trace[794348700] 'agreement among raft nodes before linearized reading' (duration: 109.620668ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:49:46.762864Z","caller":"traceutil/trace.go:171","msg":"trace[476930968] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1019; }","duration":"117.504287ms","start":"2026-02-20T15:49:46.645326Z","end":"2026-02-20T15:49:46.76283Z","steps":["trace[476930968] 'read index received' (duration: 117.244033ms)","trace[476930968] 'applied index is now lower than readState.Index' (duration: 258.874µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:49:46.762962Z","caller":"traceutil/trace.go:171","msg":"trace[1187950855] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"147.153455ms","start":"2026-02-20T15:49:46.61577Z","end":"2026-02-20T15:49:46.762924Z","steps":["trace[1187950855] 'process raft request' (duration: 146.83278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:49:46.763268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.927353ms","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-02-20T15:49:46.763335Z","caller":"traceutil/trace.go:171","msg":"trace[1969826327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:975; }","duration":"118.055506ms","start":"2026-02-20T15:49:46.645264Z","end":"2026-02-20T15:49:46.76332Z","steps":["trace[1969826327] 'agreement among raft nodes before linearized reading' (duration: 117.831552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:49:47.013046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.609463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T15:49:47.013407Z","caller":"traceutil/trace.go:171","msg":"trace[241102065] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1020; }","duration":"196.43706ms","start":"2026-02-20T15:49:46.816948Z","end":"2026-02-20T15:49:47.013385Z","steps":["trace[241102065] 'read index received' (duration: 12.35252ms)","trace[241102065] 'applied index is now lower than readState.Index' (duration: 184.0829ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:49:47.013519Z","caller":"traceutil/trace.go:171","msg":"trace[1922244940] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"244.197721ms","start":"2026-02-20T15:49:46.769302Z","end":"2026-02-20T15:49:47.013499Z","steps":["trace[1922244940] 'process raft request' (duration: 243.973107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:49:47.013684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.733654ms","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-02-20T15:49:47.013761Z","caller":"traceutil/trace.go:171","msg":"trace[194356307] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:978; }","duration":"196.852747ms","start":"2026-02-20T15:49:46.816893Z","end":"2026-02-20T15:49:47.013745Z","steps":["trace[194356307] 'agreement among raft nodes before linearized reading' (duration: 196.597973ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:49:47.013665Z","caller":"traceutil/trace.go:171","msg":"trace[1181147410] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"245.295848ms","start":"2026-02-20T15:49:46.768278Z","end":"2026-02-20T15:49:47.013574Z","steps":["trace[1181147410] 'process raft request' (duration: 61.046625ms)","trace[1181147410] 'compare' (duration: 183.43483ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:49:47.013618Z","caller":"traceutil/trace.go:171","msg":"trace[1039271538] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"243.281916ms","start":"2026-02-20T15:49:46.770291Z","end":"2026-02-20T15:49:47.013573Z","steps":["trace[1039271538] 'process raft request' (duration: 243.050312ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:49:47.450943Z","caller":"traceutil/trace.go:171","msg":"trace[1087371549] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1023; }","duration":"400.755516ms","start":"2026-02-20T15:49:47.050155Z","end":"2026-02-20T15:49:47.450911Z","steps":["trace[1087371549] 'read index received' (duration: 394.689168ms)","trace[1087371549] 'applied index is now lower than readState.Index' (duration: 6.064078ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:49:47.450978Z","caller":"traceutil/trace.go:171","msg":"trace[1266433747] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"431.731933ms","start":"2026-02-20T15:49:47.019214Z","end":"2026-02-20T15:49:47.450946Z","steps":["trace[1266433747] 'process raft request' (duration: 425.647226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:49:47.45111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.929618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:49:47.451158Z","caller":"traceutil/trace.go:171","msg":"trace[1284958018] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:979; }","duration":"401.060421ms","start":"2026-02-20T15:49:47.050081Z","end":"2026-02-20T15:49:47.451141Z","steps":["trace[1284958018] 'agreement among raft nodes before linearized reading' (duration: 400.943369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:49:47.451153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:49:47.019195Z","time spent":"431.852605ms","remote":"127.0.0.1:57490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:49:47.451204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:49:47.050049Z","time spent":"401.145472ms","remote":"127.0.0.1:46546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "}