{"level":"warn","ts":"2026-02-19T02:51:49.861854Z","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-19T02:51:49.861943Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.90:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.90:2380","--initial-cluster=instance=https://199.19.213.90:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.90:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.90: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-19T02:51:49.86202Z","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-19T02:51:49.862033Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-02-19T02:51:49.86205Z","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-19T02:51:49.862762Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"]} {"level":"info","ts":"2026-02-19T02:51:49.862864Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.90: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-19T02:51:49.867538Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.375934ms"} {"level":"info","ts":"2026-02-19T02:51:49.873318Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2b21fdc9c735cc27","cluster-id":"77163f3b44f8f1db"} {"level":"info","ts":"2026-02-19T02:51:49.873372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=()"} {"level":"info","ts":"2026-02-19T02:51:49.8734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 0"} {"level":"info","ts":"2026-02-19T02:51:49.873414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2b21fdc9c735cc27 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-19T02:51:49.873422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 1"} {"level":"info","ts":"2026-02-19T02:51:49.873453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"warn","ts":"2026-02-19T02:51:49.880417Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-19T02:51:49.882433Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-19T02:51:49.883922Z","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-19T02:51:49.886414Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2b21fdc9c735cc27","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-19T02:51:49.886605Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2b21fdc9c735cc27","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-19T02:51:49.886661Z","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-19T02:51:49.886701Z","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-19T02:51:49.886708Z","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-19T02:51:49.8914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"info","ts":"2026-02-19T02:51:49.891496Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","added-peer-id":"2b21fdc9c735cc27","added-peer-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-02-19T02:51:49.893209Z","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-19T02:51:49.893281Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-02-19T02:51:49.893349Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-02-19T02:51:49.89404Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2b21fdc9c735cc27","initial-advertise-peer-urls":["https://199.19.213.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-19T02:51:49.894105Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-19T02:51:50.674065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 is starting a new election at term 1"} {"level":"info","ts":"2026-02-19T02:51:50.674106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-19T02:51:50.67412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgPreVoteResp from 2b21fdc9c735cc27 at term 1"} {"level":"info","ts":"2026-02-19T02:51:50.67413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became candidate at term 2"} {"level":"info","ts":"2026-02-19T02:51:50.674135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgVoteResp from 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-02-19T02:51:50.674143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became leader at term 2"} {"level":"info","ts":"2026-02-19T02:51:50.67415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2b21fdc9c735cc27 elected leader 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-02-19T02:51:50.674922Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2b21fdc9c735cc27","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.90:2379]}","request-path":"/0/members/2b21fdc9c735cc27/attributes","cluster-id":"77163f3b44f8f1db","publish-timeout":"7s"} {"level":"info","ts":"2026-02-19T02:51:50.674934Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T02:51:50.675033Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T02:51:50.674981Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:51:50.675097Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-19T02:51:50.675217Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-19T02:51:50.677351Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-19T02:51:50.680217Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.90:2379"} {"level":"info","ts":"2026-02-19T02:51:50.682711Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:51:50.682794Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:51:50.68283Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-19T02:51:53.958467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.993373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:51:53.958591Z","caller":"traceutil/trace.go:171","msg":"trace[81425124] linearizableReadLoop","detail":"{readStateIndex:238; appliedIndex:237; }","duration":"354.582908ms","start":"2026-02-19T02:51:53.603996Z","end":"2026-02-19T02:51:53.958579Z","steps":["trace[81425124] 'read index received' (duration: 164.14467ms)","trace[81425124] 'applied index is now lower than readState.Index' (duration: 190.436428ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:53.958635Z","caller":"traceutil/trace.go:171","msg":"trace[1340896838] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"354.986293ms","start":"2026-02-19T02:51:53.603627Z","end":"2026-02-19T02:51:53.958613Z","steps":["trace[1340896838] 'process raft request' (duration: 164.525125ms)","trace[1340896838] 'compare' (duration: 189.886861ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:51:53.958685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.68981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:113"} {"level":"info","ts":"2026-02-19T02:51:53.958707Z","caller":"traceutil/trace.go:171","msg":"trace[1258598330] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:233; }","duration":"354.75681ms","start":"2026-02-19T02:51:53.603944Z","end":"2026-02-19T02:51:53.958701Z","steps":["trace[1258598330] 'agreement among raft nodes before linearized reading' (duration: 354.674999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:53.958727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:53.60393Z","time spent":"354.791561ms","remote":"127.0.0.1:40768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":135,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-02-19T02:51:53.95872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:53.603599Z","time spent":"355.082784ms","remote":"127.0.0.1:40804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":984,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:51:54.178712Z","caller":"traceutil/trace.go:171","msg":"trace[1771977681] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"155.635279ms","start":"2026-02-19T02:51:54.023055Z","end":"2026-02-19T02:51:54.178691Z","steps":["trace[1771977681] 'process raft request' (duration: 155.596369ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:54.178766Z","caller":"traceutil/trace.go:171","msg":"trace[2063820593] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"169.476346ms","start":"2026-02-19T02:51:54.009267Z","end":"2026-02-19T02:51:54.178743Z","steps":["trace[2063820593] 'process raft request' (duration: 138.892048ms)","trace[2063820593] 'compare' (duration: 30.347065ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:54.427507Z","caller":"traceutil/trace.go:171","msg":"trace[832460972] linearizableReadLoop","detail":"{readStateIndex:248; appliedIndex:247; }","duration":"210.395378ms","start":"2026-02-19T02:51:54.217085Z","end":"2026-02-19T02:51:54.427481Z","steps":["trace[832460972] 'read index received' (duration: 152.708175ms)","trace[832460972] 'applied index is now lower than readState.Index' (duration: 57.686133ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:54.42761Z","caller":"traceutil/trace.go:171","msg":"trace[11804919] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"227.24165ms","start":"2026-02-19T02:51:54.200334Z","end":"2026-02-19T02:51:54.427575Z","steps":["trace[11804919] 'process raft request' (duration: 169.438496ms)","trace[11804919] 'compare' (duration: 57.575761ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:51:54.427714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.61123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:51:54.42775Z","caller":"traceutil/trace.go:171","msg":"trace[1686190020] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:0; response_revision:242; }","duration":"210.711391ms","start":"2026-02-19T02:51:54.217029Z","end":"2026-02-19T02:51:54.42774Z","steps":["trace[1686190020] 'agreement among raft nodes before linearized reading' (duration: 210.59798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:54.427769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.238821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:51:54.42787Z","caller":"traceutil/trace.go:171","msg":"trace[341261125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:242; }","duration":"193.462004ms","start":"2026-02-19T02:51:54.234387Z","end":"2026-02-19T02:51:54.427849Z","steps":["trace[341261125] 'agreement among raft nodes before linearized reading' (duration: 193.297212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:54.427863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.710445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:51:54.427998Z","caller":"traceutil/trace.go:171","msg":"trace[832791166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:242; }","duration":"172.975178ms","start":"2026-02-19T02:51:54.255005Z","end":"2026-02-19T02:51:54.42798Z","steps":["trace[832791166] 'agreement among raft nodes before linearized reading' (duration: 172.690295ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:56.620216Z","caller":"traceutil/trace.go:171","msg":"trace[344412193] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:317; }","duration":"117.492239ms","start":"2026-02-19T02:51:56.502708Z","end":"2026-02-19T02:51:56.6202Z","steps":["trace[344412193] 'read index received' (duration: 117.395418ms)","trace[344412193] 'applied index is now lower than readState.Index' (duration: 95.761µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:56.620287Z","caller":"traceutil/trace.go:171","msg":"trace[1671051748] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"129.373231ms","start":"2026-02-19T02:51:56.490891Z","end":"2026-02-19T02:51:56.620265Z","steps":["trace[1671051748] 'process raft request' (duration: 129.215119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:56.620344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.631461ms","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-19T02:51:56.620373Z","caller":"traceutil/trace.go:171","msg":"trace[1028278109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:308; }","duration":"117.704002ms","start":"2026-02-19T02:51:56.502659Z","end":"2026-02-19T02:51:56.620363Z","steps":["trace[1028278109] 'agreement among raft nodes before linearized reading' (duration: 117.634872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:56.620427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.300364ms","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-19T02:51:56.620584Z","caller":"traceutil/trace.go:171","msg":"trace[1985884730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:308; }","duration":"115.500436ms","start":"2026-02-19T02:51:56.50506Z","end":"2026-02-19T02:51:56.62056Z","steps":["trace[1985884730] 'agreement among raft nodes before linearized reading' (duration: 115.289253ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:57.651409Z","caller":"traceutil/trace.go:171","msg":"trace[714638267] transaction","detail":"{read_only:false; number_of_response:0; response_revision:315; }","duration":"433.331611ms","start":"2026-02-19T02:51:57.218055Z","end":"2026-02-19T02:51:57.651387Z","steps":["trace[714638267] 'process raft request' (duration: 433.24043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:57.651552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:57.218042Z","time spent":"433.446453ms","remote":"127.0.0.1:40910","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:51:57.953319Z","caller":"traceutil/trace.go:171","msg":"trace[1401839816] transaction","detail":"{read_only:false; number_of_response:0; response_revision:315; }","duration":"734.972791ms","start":"2026-02-19T02:51:57.218312Z","end":"2026-02-19T02:51:57.953285Z","steps":["trace[1401839816] 'process raft request' (duration: 734.796408ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:57.953405Z","caller":"traceutil/trace.go:171","msg":"trace[1487215112] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:326; }","duration":"629.807113ms","start":"2026-02-19T02:51:57.323565Z","end":"2026-02-19T02:51:57.953372Z","steps":["trace[1487215112] 'read index received' (duration: 327.89326ms)","trace[1487215112] 'applied index is now lower than readState.Index' (duration: 301.912813ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:51:57.953469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:57.218304Z","time spent":"735.101492ms","remote":"127.0.0.1:40910","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:51:57.953507Z","caller":"traceutil/trace.go:171","msg":"trace[536311210] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"712.814938ms","start":"2026-02-19T02:51:57.240682Z","end":"2026-02-19T02:51:57.953497Z","steps":["trace[536311210] 'process raft request' (duration: 712.569545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:57.953607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:57.240662Z","time spent":"712.871478ms","remote":"127.0.0.1:40934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:51:57.953605Z","caller":"traceutil/trace.go:171","msg":"trace[567716069] transaction","detail":"{read_only:false; number_of_response:0; response_revision:315; }","duration":"735.197684ms","start":"2026-02-19T02:51:57.218382Z","end":"2026-02-19T02:51:57.953579Z","steps":["trace[567716069] 'process raft request' (duration: 734.84933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:57.953725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:57.218377Z","time spent":"735.288045ms","remote":"127.0.0.1:40910","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T02:51:57.953966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.454887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3952"} {"level":"info","ts":"2026-02-19T02:51:57.954005Z","caller":"traceutil/trace.go:171","msg":"trace[2111556110] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:317; }","duration":"301.495668ms","start":"2026-02-19T02:51:57.652497Z","end":"2026-02-19T02:51:57.953993Z","steps":["trace[2111556110] 'agreement among raft nodes before linearized reading' (duration: 301.354296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:57.954057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:57.652457Z","time spent":"301.590869ms","remote":"127.0.0.1:40910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":3974,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-02-19T02:51:57.954067Z","caller":"traceutil/trace.go:171","msg":"trace[678204691] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"693.790533ms","start":"2026-02-19T02:51:57.260258Z","end":"2026-02-19T02:51:57.954049Z","steps":["trace[678204691] 'process raft request' (duration: 693.059594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:57.954011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.43476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-19T02:51:57.95419Z","caller":"traceutil/trace.go:171","msg":"trace[335144965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:317; }","duration":"630.639413ms","start":"2026-02-19T02:51:57.323531Z","end":"2026-02-19T02:51:57.954171Z","steps":["trace[335144965] 'agreement among raft nodes before linearized reading' (duration: 630.312349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:57.954243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:57.323516Z","time spent":"630.708224ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T02:51:57.954368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:57.260232Z","time spent":"693.873793ms","remote":"127.0.0.1:40910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4241,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:51:58.229779Z","caller":"traceutil/trace.go:171","msg":"trace[2147236491] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:330; }","duration":"271.872945ms","start":"2026-02-19T02:51:57.957879Z","end":"2026-02-19T02:51:58.229752Z","steps":["trace[2147236491] 'read index received' (duration: 183.738113ms)","trace[2147236491] 'applied index is now lower than readState.Index' (duration: 88.133862ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:51:58.229944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.040136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:51:58.229934Z","caller":"traceutil/trace.go:171","msg":"trace[81787572] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"272.595403ms","start":"2026-02-19T02:51:57.957303Z","end":"2026-02-19T02:51:58.229899Z","steps":["trace[81787572] 'process raft request' (duration: 184.366001ms)","trace[81787572] 'compare' (duration: 87.970269ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:58.229979Z","caller":"traceutil/trace.go:171","msg":"trace[638197216] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:318; }","duration":"272.133998ms","start":"2026-02-19T02:51:57.957832Z","end":"2026-02-19T02:51:58.229966Z","steps":["trace[638197216] 'agreement among raft nodes before linearized reading' (duration: 272.056347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:58.230184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.218388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3444"} {"level":"info","ts":"2026-02-19T02:51:58.230264Z","caller":"traceutil/trace.go:171","msg":"trace[274079408] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:318; }","duration":"272.35414ms","start":"2026-02-19T02:51:57.95789Z","end":"2026-02-19T02:51:58.230244Z","steps":["trace[274079408] 'agreement among raft nodes before linearized reading' (duration: 272.223969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:58.805903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710898729046832085,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T02:51:59.188741Z","caller":"traceutil/trace.go:171","msg":"trace[301706744] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:332; }","duration":"883.016158ms","start":"2026-02-19T02:51:58.305701Z","end":"2026-02-19T02:51:59.188718Z","steps":["trace[301706744] 'read index received' (duration: 814.023855ms)","trace[301706744] 'applied index is now lower than readState.Index' (duration: 68.991173ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:59.188826Z","caller":"traceutil/trace.go:171","msg":"trace[1233682283] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"948.946897ms","start":"2026-02-19T02:51:58.239861Z","end":"2026-02-19T02:51:59.188808Z","steps":["trace[1233682283] 'process raft request' (duration: 879.902204ms)","trace[1233682283] 'compare' (duration: 68.84097ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:51:59.188905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.19636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"warn","ts":"2026-02-19T02:51:59.188929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:58.239847Z","time spent":"949.017978ms","remote":"127.0.0.1:40910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4579,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:51:59.188984Z","caller":"traceutil/trace.go:171","msg":"trace[1142174826] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:320; }","duration":"883.26644ms","start":"2026-02-19T02:51:58.305663Z","end":"2026-02-19T02:51:59.188929Z","steps":["trace[1142174826] 'agreement among raft nodes before linearized reading' (duration: 883.177709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:59.189024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:58.305649Z","time spent":"883.365551ms","remote":"127.0.0.1:40934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"warn","ts":"2026-02-19T02:51:59.189096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.827789ms","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-19T02:51:59.189118Z","caller":"traceutil/trace.go:171","msg":"trace[359373127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:320; }","duration":"494.88381ms","start":"2026-02-19T02:51:58.694227Z","end":"2026-02-19T02:51:59.189111Z","steps":["trace[359373127] 'agreement among raft nodes before linearized reading' (duration: 494.84078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:59.189138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:58.69421Z","time spent":"494.923061ms","remote":"127.0.0.1:40998","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":"warn","ts":"2026-02-19T02:51:59.189439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.03608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-19T02:51:59.189416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.339243ms","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-19T02:51:59.189475Z","caller":"traceutil/trace.go:171","msg":"trace[1329390789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:320; }","duration":"494.085021ms","start":"2026-02-19T02:51:58.69538Z","end":"2026-02-19T02:51:59.189465Z","steps":["trace[1329390789] 'agreement among raft nodes before linearized reading' (duration: 494.02655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:59.189504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:58.695374Z","time spent":"494.123981ms","remote":"127.0.0.1:40998","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":"info","ts":"2026-02-19T02:51:59.189506Z","caller":"traceutil/trace.go:171","msg":"trace[1236270595] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:320; }","duration":"174.480745ms","start":"2026-02-19T02:51:59.015002Z","end":"2026-02-19T02:51:59.189483Z","steps":["trace[1236270595] 'agreement among raft nodes before linearized reading' (duration: 174.313343ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:59.409844Z","caller":"traceutil/trace.go:171","msg":"trace[38924444] linearizableReadLoop","detail":"{readStateIndex:337; appliedIndex:336; }","duration":"183.796424ms","start":"2026-02-19T02:51:59.22602Z","end":"2026-02-19T02:51:59.409817Z","steps":["trace[38924444] 'read index received' (duration: 91.972963ms)","trace[38924444] 'applied index is now lower than readState.Index' (duration: 91.821911ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:59.409943Z","caller":"traceutil/trace.go:171","msg":"trace[2049848713] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"203.597777ms","start":"2026-02-19T02:51:59.206311Z","end":"2026-02-19T02:51:59.409908Z","steps":["trace[2049848713] 'process raft request' (duration: 111.739716ms)","trace[2049848713] 'compare' (duration: 91.505477ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:51:59.410144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.981356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:51:59.410206Z","caller":"traceutil/trace.go:171","msg":"trace[1686181377] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:324; }","duration":"184.191039ms","start":"2026-02-19T02:51:59.225995Z","end":"2026-02-19T02:51:59.410186Z","steps":["trace[1686181377] 'agreement among raft nodes before linearized reading' (duration: 183.968486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:59.856676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.120028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:51:59.85679Z","caller":"traceutil/trace.go:171","msg":"trace[419891335] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"440.619708ms","start":"2026-02-19T02:51:59.41615Z","end":"2026-02-19T02:51:59.85677Z","steps":["trace[419891335] 'process raft request' (duration: 121.336778ms)","trace[419891335] 'compare' (duration: 318.949906ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:51:59.856857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:59.416128Z","time spent":"440.699639ms","remote":"127.0.0.1:40934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:51:59.987172Z","caller":"traceutil/trace.go:171","msg":"trace[65170602] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:338; }","duration":"126.266477ms","start":"2026-02-19T02:51:59.860865Z","end":"2026-02-19T02:51:59.987131Z","steps":["trace[65170602] 'read index received' (duration: 106.033438ms)","trace[65170602] 'applied index is now lower than readState.Index' (duration: 20.232039ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:51:59.987319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.427388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:51:59.987354Z","caller":"traceutil/trace.go:171","msg":"trace[1847776743] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:0; response_revision:326; }","duration":"126.52648ms","start":"2026-02-19T02:51:59.860817Z","end":"2026-02-19T02:51:59.987343Z","steps":["trace[1847776743] 'agreement among raft nodes before linearized reading' (duration: 126.434839ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:59.987336Z","caller":"traceutil/trace.go:171","msg":"trace[1853825840] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"567.163018ms","start":"2026-02-19T02:51:59.420137Z","end":"2026-02-19T02:51:59.9873Z","steps":["trace[1853825840] 'process raft request' (duration: 546.729717ms)","trace[1853825840] 'compare' (duration: 20.123527ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:51:59.987517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:59.420115Z","time spent":"567.29788ms","remote":"127.0.0.1:40910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7021,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:52:00.211912Z","caller":"traceutil/trace.go:171","msg":"trace[1592614557] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"150.205432ms","start":"2026-02-19T02:52:00.061684Z","end":"2026-02-19T02:52:00.211889Z","steps":["trace[1592614557] 'process raft request' (duration: 150.146021ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:00.211972Z","caller":"traceutil/trace.go:171","msg":"trace[184072434] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"154.544423ms","start":"2026-02-19T02:52:00.057403Z","end":"2026-02-19T02:52:00.211947Z","steps":["trace[184072434] 'process raft request' (duration: 70.912882ms)","trace[184072434] 'compare' (duration: 83.362898ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:04.093931Z","caller":"traceutil/trace.go:171","msg":"trace[503134610] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"485.556894ms","start":"2026-02-19T02:52:03.608345Z","end":"2026-02-19T02:52:04.093902Z","steps":["trace[503134610] 'process raft request' (duration: 449.848451ms)","trace[503134610] 'compare' (duration: 35.561421ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:52:04.094141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:03.608329Z","time spent":"485.732296ms","remote":"127.0.0.1:40750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:52:14.70359Z","caller":"traceutil/trace.go:171","msg":"trace[1354194386] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"290.611079ms","start":"2026-02-19T02:52:14.412957Z","end":"2026-02-19T02:52:14.703568Z","steps":["trace[1354194386] 'process raft request' (duration: 290.466508ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:20.633446Z","caller":"traceutil/trace.go:171","msg":"trace[1805332256] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"251.804545ms","start":"2026-02-19T02:52:20.38162Z","end":"2026-02-19T02:52:20.633424Z","steps":["trace[1805332256] 'process raft request' (duration: 251.460111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:23.235501Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710898729046832477,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T02:52:23.609662Z","caller":"traceutil/trace.go:171","msg":"trace[2065676185] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"932.192663ms","start":"2026-02-19T02:52:22.677446Z","end":"2026-02-19T02:52:23.609639Z","steps":["trace[2065676185] 'process raft request' (duration: 932.051118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:23.6098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:22.677421Z","time spent":"932.306117ms","remote":"127.0.0.1:40896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4212,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:52:23.614547Z","caller":"traceutil/trace.go:171","msg":"trace[1956844967] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:528; }","duration":"880.063509ms","start":"2026-02-19T02:52:22.734433Z","end":"2026-02-19T02:52:23.614497Z","steps":["trace[1956844967] 'read index received' (duration: 875.058656ms)","trace[1956844967] 'applied index is now lower than readState.Index' (duration: 5.003723ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:23.61485Z","caller":"traceutil/trace.go:171","msg":"trace[801531617] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"723.667427ms","start":"2026-02-19T02:52:22.891168Z","end":"2026-02-19T02:52:23.614835Z","steps":["trace[801531617] 'process raft request' (duration: 723.215172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:23.614961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:22.89114Z","time spent":"723.74956ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:52:23.615308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.875386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-19T02:52:23.615352Z","caller":"traceutil/trace.go:171","msg":"trace[970433086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:511; }","duration":"880.96333ms","start":"2026-02-19T02:52:22.734378Z","end":"2026-02-19T02:52:23.615341Z","steps":["trace[970433086] 'agreement among raft nodes before linearized reading' (duration: 880.829245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:23.615384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:22.734351Z","time spent":"881.025861ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T02:52:23.615592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.628231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:52:23.615803Z","caller":"traceutil/trace.go:171","msg":"trace[1886481481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"526.865709ms","start":"2026-02-19T02:52:23.088897Z","end":"2026-02-19T02:52:23.615763Z","steps":["trace[1886481481] 'agreement among raft nodes before linearized reading' (duration: 526.622021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:23.616872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:23.088878Z","time spent":"527.973028ms","remote":"127.0.0.1:40702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:52:23.616113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.428414ms","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-19T02:52:23.617088Z","caller":"traceutil/trace.go:171","msg":"trace[668044700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:511; }","duration":"257.412238ms","start":"2026-02-19T02:52:23.359652Z","end":"2026-02-19T02:52:23.617064Z","steps":["trace[668044700] 'agreement among raft nodes before linearized reading' (duration: 256.413674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:23.616207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.026995ms","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-19T02:52:23.617309Z","caller":"traceutil/trace.go:171","msg":"trace[242776926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"265.138414ms","start":"2026-02-19T02:52:23.352127Z","end":"2026-02-19T02:52:23.617265Z","steps":["trace[242776926] 'agreement among raft nodes before linearized reading' (duration: 264.054167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:23.615718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.089477ms","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-19T02:52:23.617411Z","caller":"traceutil/trace.go:171","msg":"trace[2023695254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"247.821958ms","start":"2026-02-19T02:52:23.369575Z","end":"2026-02-19T02:52:23.617397Z","steps":["trace[2023695254] 'agreement among raft nodes before linearized reading' (duration: 246.105748ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:29.857773Z","caller":"traceutil/trace.go:171","msg":"trace[550798322] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"199.749087ms","start":"2026-02-19T02:52:29.658Z","end":"2026-02-19T02:52:29.857749Z","steps":["trace[550798322] 'read index received' (duration: 199.561051ms)","trace[550798322] 'applied index is now lower than readState.Index' (duration: 187.006µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:29.857917Z","caller":"traceutil/trace.go:171","msg":"trace[638302285] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"204.684008ms","start":"2026-02-19T02:52:29.653223Z","end":"2026-02-19T02:52:29.857907Z","steps":["trace[638302285] 'process raft request' (duration: 204.372048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:29.858086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.036289ms","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-19T02:52:29.858157Z","caller":"traceutil/trace.go:171","msg":"trace[938855551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"197.129222ms","start":"2026-02-19T02:52:29.66101Z","end":"2026-02-19T02:52:29.858139Z","steps":["trace[938855551] 'agreement among raft nodes before linearized reading' (duration: 197.013628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:29.858304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.299896ms","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-19T02:52:29.858343Z","caller":"traceutil/trace.go:171","msg":"trace[168939806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:551; }","duration":"200.338937ms","start":"2026-02-19T02:52:29.657995Z","end":"2026-02-19T02:52:29.858334Z","steps":["trace[168939806] 'agreement among raft nodes before linearized reading' (duration: 200.279385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:29.858492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.741032ms","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-19T02:52:29.858551Z","caller":"traceutil/trace.go:171","msg":"trace[1299605194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"193.808675ms","start":"2026-02-19T02:52:29.664732Z","end":"2026-02-19T02:52:29.858541Z","steps":["trace[1299605194] 'agreement among raft nodes before linearized reading' (duration: 193.734353ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:30.734458Z","caller":"traceutil/trace.go:171","msg":"trace[979206121] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"871.634441ms","start":"2026-02-19T02:52:29.862801Z","end":"2026-02-19T02:52:30.734435Z","steps":["trace[979206121] 'process raft request' (duration: 871.477476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:30.734677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:29.862784Z","time spent":"871.794195ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:52:30.865706Z","caller":"traceutil/trace.go:171","msg":"trace[175383237] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.002776117s","start":"2026-02-19T02:52:29.862901Z","end":"2026-02-19T02:52:30.865677Z","steps":["trace[175383237] 'process raft request' (duration: 943.773646ms)","trace[175383237] 'compare' (duration: 58.837116ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:52:30.865884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:29.862894Z","time spent":"1.002891891s","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:52:30.865891Z","caller":"traceutil/trace.go:171","msg":"trace[250550767] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:572; }","duration":"571.137805ms","start":"2026-02-19T02:52:30.294725Z","end":"2026-02-19T02:52:30.865863Z","steps":["trace[250550767] 'read index received' (duration: 439.544584ms)","trace[250550767] 'applied index is now lower than readState.Index' (duration: 131.591361ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:30.866017Z","caller":"traceutil/trace.go:171","msg":"trace[428600117] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.002205208s","start":"2026-02-19T02:52:29.863774Z","end":"2026-02-19T02:52:30.865979Z","steps":["trace[428600117] 'process raft request' (duration: 1.001863327s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:30.866143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.422745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-s8rzm\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-19T02:52:30.866182Z","caller":"traceutil/trace.go:171","msg":"trace[1756099186] range","detail":"{range_begin:/registry/pods/kube-system/cilium-s8rzm; range_end:; response_count:1; response_revision:554; }","duration":"571.514617ms","start":"2026-02-19T02:52:30.294656Z","end":"2026-02-19T02:52:30.866171Z","steps":["trace[1756099186] 'agreement among raft nodes before linearized reading' (duration: 571.312231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:30.866215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:30.294638Z","time spent":"571.56907ms","remote":"127.0.0.1:40910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16674,"request content":"key:\"/registry/pods/kube-system/cilium-s8rzm\" "} {"level":"warn","ts":"2026-02-19T02:52:30.866207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:29.863755Z","time spent":"1.002347113s","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:52:31.59029Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710898729046832571,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T02:52:31.870918Z","caller":"traceutil/trace.go:171","msg":"trace[238458979] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"780.862312ms","start":"2026-02-19T02:52:31.090041Z","end":"2026-02-19T02:52:31.870903Z","steps":["trace[238458979] 'read index received' (duration: 780.657545ms)","trace[238458979] 'applied index is now lower than readState.Index' (duration: 204.176µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:31.870952Z","caller":"traceutil/trace.go:171","msg":"trace[851018192] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"989.540459ms","start":"2026-02-19T02:52:30.881392Z","end":"2026-02-19T02:52:31.870932Z","steps":["trace[851018192] 'process raft request' (duration: 989.398615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:31.871042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.977765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:52:31.871069Z","caller":"traceutil/trace.go:171","msg":"trace[1622143152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"781.062998ms","start":"2026-02-19T02:52:31.089999Z","end":"2026-02-19T02:52:31.871062Z","steps":["trace[1622143152] 'agreement among raft nodes before linearized reading' (duration: 780.998366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:31.871089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:31.089982Z","time spent":"781.103199ms","remote":"127.0.0.1:40702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:52:31.871086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:30.881365Z","time spent":"989.656353ms","remote":"127.0.0.1:40910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:52:34.564094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.824865565s","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-19T02:52:34.564307Z","caller":"traceutil/trace.go:171","msg":"trace[1334863883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"1.825141524s","start":"2026-02-19T02:52:32.73913Z","end":"2026-02-19T02:52:34.564272Z","steps":["trace[1334863883] 'range keys from in-memory index tree' (duration: 1.82468298s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:34.564326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.978445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-19T02:52:34.564406Z","caller":"traceutil/trace.go:171","msg":"trace[1640743009] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:556; }","duration":"674.10348ms","start":"2026-02-19T02:52:33.890283Z","end":"2026-02-19T02:52:34.564387Z","steps":["trace[1640743009] 'range keys from in-memory index tree' (duration: 673.890803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:34.564407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:32.739111Z","time spent":"1.825251967s","remote":"127.0.0.1:40998","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-02-19T02:52:34.564447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:33.890264Z","time spent":"674.174572ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T02:52:34.564463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.572459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.90\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-19T02:52:34.564501Z","caller":"traceutil/trace.go:171","msg":"trace[1642442774] range","detail":"{range_begin:/registry/masterleases/199.19.213.90; range_end:; response_count:1; response_revision:556; }","duration":"961.637411ms","start":"2026-02-19T02:52:33.602853Z","end":"2026-02-19T02:52:34.56449Z","steps":["trace[1642442774] 'range keys from in-memory index tree' (duration: 961.509218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:34.564524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:33.602837Z","time spent":"961.680762ms","remote":"127.0.0.1:40750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.90\" "} {"level":"warn","ts":"2026-02-19T02:52:34.56452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.695142851s","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-19T02:52:34.564604Z","caller":"traceutil/trace.go:171","msg":"trace[1325524189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"1.695267795s","start":"2026-02-19T02:52:32.869313Z","end":"2026-02-19T02:52:34.564581Z","steps":["trace[1325524189] 'range keys from in-memory index tree' (duration: 1.695045458s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:34.56466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:32.869296Z","time spent":"1.695352198s","remote":"127.0.0.1:40998","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":"warn","ts":"2026-02-19T02:52:34.56475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.692694053s","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-19T02:52:34.564773Z","caller":"traceutil/trace.go:171","msg":"trace[1930455046] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"1.692740965s","start":"2026-02-19T02:52:32.872025Z","end":"2026-02-19T02:52:34.564766Z","steps":["trace[1930455046] 'range keys from in-memory index tree' (duration: 1.692634422s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:34.564791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:32.87201Z","time spent":"1.692777117s","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T02:52:34.564783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.476888692s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:52:34.564844Z","caller":"traceutil/trace.go:171","msg":"trace[624614755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"1.476984605s","start":"2026-02-19T02:52:33.087835Z","end":"2026-02-19T02:52:34.56482Z","steps":["trace[624614755] 'range keys from in-memory index tree' (duration: 1.476789489s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:34.564885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:33.087814Z","time spent":"1.477061407s","remote":"127.0.0.1:40702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T02:52:34.613018Z","caller":"traceutil/trace.go:171","msg":"trace[2104139547] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"691.687557ms","start":"2026-02-19T02:52:33.921312Z","end":"2026-02-19T02:52:34.613Z","steps":["trace[2104139547] 'process raft request' (duration: 691.585274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:34.613144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:33.921291Z","time spent":"691.791701ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:52:36.981173Z","caller":"traceutil/trace.go:171","msg":"trace[1115152423] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"362.727165ms","start":"2026-02-19T02:52:36.618423Z","end":"2026-02-19T02:52:36.98115Z","steps":["trace[1115152423] 'read index received' (duration: 362.583971ms)","trace[1115152423] 'applied index is now lower than readState.Index' (duration: 142.204µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:36.981255Z","caller":"traceutil/trace.go:171","msg":"trace[1161356836] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"446.283864ms","start":"2026-02-19T02:52:36.53492Z","end":"2026-02-19T02:52:36.981204Z","steps":["trace[1161356836] 'process raft request' (duration: 446.058357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:36.981399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:36.534894Z","time spent":"446.434069ms","remote":"127.0.0.1:40764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T02:52:36.981418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.970322ms","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-19T02:52:36.981498Z","caller":"traceutil/trace.go:171","msg":"trace[1246774187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:594; }","duration":"363.085436ms","start":"2026-02-19T02:52:36.61839Z","end":"2026-02-19T02:52:36.981475Z","steps":["trace[1246774187] 'agreement among raft nodes before linearized reading' (duration: 362.87968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:36.98154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:36.61837Z","time spent":"363.161158ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-19T02:52:36.984703Z","caller":"traceutil/trace.go:171","msg":"trace[359774345] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"251.236214ms","start":"2026-02-19T02:52:36.733453Z","end":"2026-02-19T02:52:36.98469Z","steps":["trace[359774345] 'process raft request' (duration: 251.068079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:36.984748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.248322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-19T02:52:36.98478Z","caller":"traceutil/trace.go:171","msg":"trace[453537522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"366.311625ms","start":"2026-02-19T02:52:36.618458Z","end":"2026-02-19T02:52:36.98477Z","steps":["trace[453537522] 'agreement among raft nodes before linearized reading' (duration: 366.16133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:36.984786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.938163ms","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-19T02:52:36.984849Z","caller":"traceutil/trace.go:171","msg":"trace[1102414834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"366.029106ms","start":"2026-02-19T02:52:36.618805Z","end":"2026-02-19T02:52:36.984834Z","steps":["trace[1102414834] 'agreement among raft nodes before linearized reading' (duration: 365.876811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:36.984886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:36.618795Z","time spent":"366.082427ms","remote":"127.0.0.1:40998","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":"warn","ts":"2026-02-19T02:52:36.984808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:36.618453Z","time spent":"366.349306ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T02:52:36.985136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.006105ms","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-19T02:52:36.985225Z","caller":"traceutil/trace.go:171","msg":"trace[1583333917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:596; }","duration":"366.115478ms","start":"2026-02-19T02:52:36.619094Z","end":"2026-02-19T02:52:36.985209Z","steps":["trace[1583333917] 'agreement among raft nodes before linearized reading' (duration: 365.559371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:36.985293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:36.619087Z","time spent":"366.19757ms","remote":"127.0.0.1:40998","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":"info","ts":"2026-02-19T02:53:56.743783Z","caller":"traceutil/trace.go:171","msg":"trace[926852674] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"129.153413ms","start":"2026-02-19T02:53:56.614607Z","end":"2026-02-19T02:53:56.74376Z","steps":["trace[926852674] 'process raft request' (duration: 129.021851ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:54:05.938423Z","caller":"traceutil/trace.go:171","msg":"trace[670602251] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1048; }","duration":"453.731331ms","start":"2026-02-19T02:54:05.484669Z","end":"2026-02-19T02:54:05.9384Z","steps":["trace[670602251] 'read index received' (duration: 14.598654ms)","trace[670602251] 'applied index is now lower than readState.Index' (duration: 439.131877ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:54:05.938494Z","caller":"traceutil/trace.go:171","msg":"trace[1616454498] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"507.789643ms","start":"2026-02-19T02:54:05.430667Z","end":"2026-02-19T02:54:05.938456Z","steps":["trace[1616454498] 'process raft request' (duration: 507.62494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:05.938627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.939805ms","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-19T02:54:05.938664Z","caller":"traceutil/trace.go:171","msg":"trace[1205355686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1006; }","duration":"454.001207ms","start":"2026-02-19T02:54:05.484651Z","end":"2026-02-19T02:54:05.938652Z","steps":["trace[1205355686] 'agreement among raft nodes before linearized reading' (duration: 453.855394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:05.938683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:54:05.430653Z","time spent":"507.947226ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:54:05.938682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.143771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:54:05.938683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.474552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-19T02:54:05.938702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:54:05.484643Z","time spent":"454.047717ms","remote":"127.0.0.1:40998","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":"info","ts":"2026-02-19T02:54:05.938751Z","caller":"traceutil/trace.go:171","msg":"trace[17646897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"337.260563ms","start":"2026-02-19T02:54:05.601473Z","end":"2026-02-19T02:54:05.938734Z","steps":["trace[17646897] 'agreement among raft nodes before linearized reading' (duration: 337.143051ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:54:05.938764Z","caller":"traceutil/trace.go:171","msg":"trace[1776926364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1006; }","duration":"451.556843ms","start":"2026-02-19T02:54:05.487193Z","end":"2026-02-19T02:54:05.93875Z","steps":["trace[1776926364] 'agreement among raft nodes before linearized reading' (duration: 451.36958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:54:05.938797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:54:05.601451Z","time spent":"337.337584ms","remote":"127.0.0.1:40702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:54:05.938797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:54:05.487177Z","time spent":"451.612305ms","remote":"127.0.0.1:40998","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\" "}