{"level":"warn","ts":"2026-03-03T02:51:35.222058Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-03T02:51:35.222126Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-03T02:51:35.225546Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-03T02:51:35.225572Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-03-03T02:51:35.225605Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-03T02:51:35.226389Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-03-03T02:51:35.226485Z","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.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-03T02:51:35.232623Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.909867ms"} {"level":"info","ts":"2026-03-03T02:51:35.240276Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-03-03T02:51:35.240348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-03-03T02:51:35.240369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-03-03T02:51:35.240386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-03T02:51:35.240394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-03-03T02:51:35.240432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-03-03T02:51:35.251156Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-03T02:51:35.252911Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-03T02:51:35.256715Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-03T02:51:35.259089Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-03T02:51:35.259279Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-03T02:51:35.259381Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-03T02:51:35.259391Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-03T02:51:35.259513Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-03T02:51:35.260932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-03-03T02:51:35.261497Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-03-03T02:51:35.26372Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-03T02:51:35.263807Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-03-03T02:51:35.263901Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-03-03T02:51:35.26494Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-03T02:51:35.264975Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-03T02:51:35.640806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-03-03T02:51:35.640847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-03T02:51:35.640866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-03-03T02:51:35.640876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-03-03T02:51:35.640881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-03-03T02:51:35.640889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-03-03T02:51:35.640896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-03-03T02:51:35.643092Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-03-03T02:51:35.643137Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T02:51:35.643132Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T02:51:35.64336Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T02:51:35.643537Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-03T02:51:35.643685Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-03T02:51:35.645109Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-03T02:51:35.64572Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T02:51:35.645872Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T02:51:35.645912Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T02:51:35.66387Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"warn","ts":"2026-03-03T02:51:50.926568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.499786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-03T02:51:50.926721Z","caller":"traceutil/trace.go:171","msg":"trace[2142426096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:368; }","duration":"147.709557ms","start":"2026-03-03T02:51:50.778988Z","end":"2026-03-03T02:51:50.926698Z","steps":["trace[2142426096] 'range keys from in-memory index tree' (duration: 147.37253ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:51:50.927661Z","caller":"traceutil/trace.go:171","msg":"trace[533616306] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"104.285956ms","start":"2026-03-03T02:51:50.823336Z","end":"2026-03-03T02:51:50.927622Z","steps":["trace[533616306] 'process raft request' (duration: 102.188106ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:51:58.209125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.069006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-03T02:51:58.20918Z","caller":"traceutil/trace.go:171","msg":"trace[1133198685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:414; }","duration":"945.168133ms","start":"2026-03-03T02:51:57.263999Z","end":"2026-03-03T02:51:58.209168Z","steps":["trace[1133198685] 'range keys from in-memory index tree' (duration: 944.987477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:51:58.209204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:51:57.263981Z","time spent":"945.217421ms","remote":"127.0.0.1:34030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-03T02:51:58.209266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.250263456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-03T02:51:58.209335Z","caller":"traceutil/trace.go:171","msg":"trace[1593494685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"1.250365753s","start":"2026-03-03T02:51:56.958953Z","end":"2026-03-03T02:51:58.209318Z","steps":["trace[1593494685] 'range keys from in-memory index tree' (duration: 1.250160339s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:51:58.209372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:51:56.958938Z","time spent":"1.250426242s","remote":"127.0.0.1:34030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T02:51:58.209428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053894927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-03T02:51:58.209498Z","caller":"traceutil/trace.go:171","msg":"trace[2044997458] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"590.928677ms","start":"2026-03-03T02:51:57.618553Z","end":"2026-03-03T02:51:58.209481Z","steps":["trace[2044997458] 'process raft request' (duration: 498.672955ms)","trace[2044997458] 'compare' (duration: 91.474283ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:51:58.209473Z","caller":"traceutil/trace.go:171","msg":"trace[2040389971] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"422.013865ms","start":"2026-03-03T02:51:57.78742Z","end":"2026-03-03T02:51:58.209434Z","steps":["trace[2040389971] 'read index received' (duration: 329.703354ms)","trace[2040389971] 'applied index is now lower than readState.Index' (duration: 92.308731ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:51:58.209513Z","caller":"traceutil/trace.go:171","msg":"trace[1601465569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:414; }","duration":"1.054019804s","start":"2026-03-03T02:51:57.155468Z","end":"2026-03-03T02:51:58.209488Z","steps":["trace[1601465569] 'range keys from in-memory index tree' (duration: 1.053802909s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:51:58.209564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:51:57.155449Z","time spent":"1.054106241s","remote":"127.0.0.1:34030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T02:51:58.209616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.165851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:51:58.209658Z","caller":"traceutil/trace.go:171","msg":"trace[1316454640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"422.256208ms","start":"2026-03-03T02:51:57.787389Z","end":"2026-03-03T02:51:58.209645Z","steps":["trace[1316454640] 'agreement among raft nodes before linearized reading' (duration: 422.157831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:51:58.209696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:51:57.787367Z","time spent":"422.320417ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T02:51:58.209976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:51:57.618524Z","time spent":"591.003746ms","remote":"127.0.0.1:34030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T02:51:58.506282Z","caller":"traceutil/trace.go:171","msg":"trace[646984226] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"291.699536ms","start":"2026-03-03T02:51:58.214556Z","end":"2026-03-03T02:51:58.506255Z","steps":["trace[646984226] 'process raft request' (duration: 268.649276ms)","trace[646984226] 'compare' (duration: 22.856585ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:51:58.506698Z","caller":"traceutil/trace.go:171","msg":"trace[761142502] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"290.287484ms","start":"2026-03-03T02:51:58.216389Z","end":"2026-03-03T02:51:58.506676Z","steps":["trace[761142502] 'process raft request' (duration: 289.823747ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:04.291763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415871129018682324,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T02:52:04.305655Z","caller":"traceutil/trace.go:171","msg":"trace[1354323177] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"539.5254ms","start":"2026-03-03T02:52:03.766109Z","end":"2026-03-03T02:52:04.305634Z","steps":["trace[1354323177] 'process raft request' (duration: 539.163057ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:04.305784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:03.766083Z","time spent":"539.625507ms","remote":"127.0.0.1:33844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317292,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T02:52:04.312481Z","caller":"traceutil/trace.go:171","msg":"trace[660773952] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:445; }","duration":"521.355827ms","start":"2026-03-03T02:52:03.791095Z","end":"2026-03-03T02:52:04.312451Z","steps":["trace[660773952] 'read index received' (duration: 514.013558ms)","trace[660773952] 'applied index is now lower than readState.Index' (duration: 7.340139ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:52:04.312597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.478775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:52:04.312672Z","caller":"traceutil/trace.go:171","msg":"trace[683515526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"521.598514ms","start":"2026-03-03T02:52:03.791055Z","end":"2026-03-03T02:52:04.312653Z","steps":["trace[683515526] 'agreement among raft nodes before linearized reading' (duration: 521.466725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:04.31273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:03.79104Z","time spent":"521.677901ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T02:52:06.73612Z","caller":"traceutil/trace.go:171","msg":"trace[1069009999] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"365.560071ms","start":"2026-03-03T02:52:06.370535Z","end":"2026-03-03T02:52:06.736095Z","steps":["trace[1069009999] 'process raft request' (duration: 365.397534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:06.736267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:06.37051Z","time spent":"365.689839ms","remote":"127.0.0.1:33932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T02:52:06.736556Z","caller":"traceutil/trace.go:171","msg":"trace[239781188] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:486; }","duration":"186.925194ms","start":"2026-03-03T02:52:06.549594Z","end":"2026-03-03T02:52:06.736519Z","steps":["trace[239781188] 'read index received' (duration: 186.916064ms)","trace[239781188] 'applied index is now lower than readState.Index' (duration: 7.17µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:52:06.736807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.023242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-03T02:52:06.736866Z","caller":"traceutil/trace.go:171","msg":"trace[1120031160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:469; }","duration":"187.16668ms","start":"2026-03-03T02:52:06.549684Z","end":"2026-03-03T02:52:06.73685Z","steps":["trace[1120031160] 'agreement among raft nodes before linearized reading' (duration: 186.997803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:06.736922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.275827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-03T02:52:06.737052Z","caller":"traceutil/trace.go:171","msg":"trace[1826086007] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:469; }","duration":"187.479073ms","start":"2026-03-03T02:52:06.549547Z","end":"2026-03-03T02:52:06.737026Z","steps":["trace[1826086007] 'agreement among raft nodes before linearized reading' (duration: 187.219778ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:06.737125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.810059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-03T02:52:06.737171Z","caller":"traceutil/trace.go:171","msg":"trace[2025940443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:469; }","duration":"118.908967ms","start":"2026-03-03T02:52:06.618253Z","end":"2026-03-03T02:52:06.737162Z","steps":["trace[2025940443] 'agreement among raft nodes before linearized reading' (duration: 118.835688ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:52:10.02746Z","caller":"traceutil/trace.go:171","msg":"trace[153384340] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"102.635871ms","start":"2026-03-03T02:52:09.924803Z","end":"2026-03-03T02:52:10.027439Z","steps":["trace[153384340] 'process raft request' (duration: 102.480414ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:52:10.154159Z","caller":"traceutil/trace.go:171","msg":"trace[1676102922] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"119.989253ms","start":"2026-03-03T02:52:10.034135Z","end":"2026-03-03T02:52:10.154125Z","steps":["trace[1676102922] 'process raft request' (duration: 116.74273ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:52:13.8408Z","caller":"traceutil/trace.go:171","msg":"trace[1653386834] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"221.290937ms","start":"2026-03-03T02:52:13.619488Z","end":"2026-03-03T02:52:13.840779Z","steps":["trace[1653386834] 'process raft request' (duration: 220.828503ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:14.389869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.541109ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-03T02:52:14.895536Z","caller":"traceutil/trace.go:171","msg":"trace[267757221] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"417.252255ms","start":"2026-03-03T02:52:14.478254Z","end":"2026-03-03T02:52:14.895506Z","steps":["trace[267757221] 'process raft request' (duration: 416.517883ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:14.895697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:14.478229Z","time spent":"417.381513ms","remote":"127.0.0.1:34030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T02:52:15.131715Z","caller":"traceutil/trace.go:171","msg":"trace[2084873255] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"231.230106ms","start":"2026-03-03T02:52:14.900461Z","end":"2026-03-03T02:52:15.131691Z","steps":["trace[2084873255] 'process raft request' (duration: 231.054038ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:52:15.131889Z","caller":"traceutil/trace.go:171","msg":"trace[1302170675] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"230.383086ms","start":"2026-03-03T02:52:14.901478Z","end":"2026-03-03T02:52:15.131862Z","steps":["trace[1302170675] 'process raft request' (duration: 230.176308ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:52:17.227714Z","caller":"traceutil/trace.go:171","msg":"trace[961186054] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"323.737937ms","start":"2026-03-03T02:52:16.903951Z","end":"2026-03-03T02:52:17.227688Z","steps":["trace[961186054] 'process raft request' (duration: 323.491689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:17.22792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:16.903933Z","time spent":"323.867795ms","remote":"127.0.0.1:34030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:52:17.227928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.891172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-03T02:52:17.228007Z","caller":"traceutil/trace.go:171","msg":"trace[1555004693] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:518; }","duration":"307.021611ms","start":"2026-03-03T02:52:16.920956Z","end":"2026-03-03T02:52:17.227978Z","steps":["trace[1555004693] 'agreement among raft nodes before linearized reading' (duration: 306.810212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:17.22805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:16.920922Z","time spent":"307.12043ms","remote":"127.0.0.1:34030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-03T02:52:17.228265Z","caller":"traceutil/trace.go:171","msg":"trace[1206659899] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"306.586154ms","start":"2026-03-03T02:52:16.921057Z","end":"2026-03-03T02:52:17.227643Z","steps":["trace[1206659899] 'read index received' (duration: 306.125868ms)","trace[1206659899] 'applied index is now lower than readState.Index' (duration: 459.256µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:52:18.751545Z","caller":"traceutil/trace.go:171","msg":"trace[151868350] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:555; }","duration":"120.254678ms","start":"2026-03-03T02:52:18.631255Z","end":"2026-03-03T02:52:18.75151Z","steps":["trace[151868350] 'read index received' (duration: 48.784904ms)","trace[151868350] 'applied index is now lower than readState.Index' (duration: 71.468074ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:52:18.751787Z","caller":"traceutil/trace.go:171","msg":"trace[402035860] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"167.504494ms","start":"2026-03-03T02:52:18.58425Z","end":"2026-03-03T02:52:18.751754Z","steps":["trace[402035860] 'process raft request' (duration: 167.179076ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:18.751885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.584625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-qh228\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-03-03T02:52:18.751942Z","caller":"traceutil/trace.go:171","msg":"trace[1257833511] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-qh228; range_end:; response_count:1; response_revision:537; }","duration":"120.691644ms","start":"2026-03-03T02:52:18.631236Z","end":"2026-03-03T02:52:18.751927Z","steps":["trace[1257833511] 'agreement among raft nodes before linearized reading' (duration: 120.426547ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:52:18.751976Z","caller":"traceutil/trace.go:171","msg":"trace[2097201165] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"167.786991ms","start":"2026-03-03T02:52:18.584177Z","end":"2026-03-03T02:52:18.751964Z","steps":["trace[2097201165] 'process raft request' (duration: 95.729572ms)","trace[2097201165] 'compare' (duration: 71.203066ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:52:18.75469Z","caller":"traceutil/trace.go:171","msg":"trace[1312738985] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"118.815088ms","start":"2026-03-03T02:52:18.635836Z","end":"2026-03-03T02:52:18.754651Z","steps":["trace[1312738985] 'process raft request' (duration: 118.648029ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:52:19.321666Z","caller":"traceutil/trace.go:171","msg":"trace[980569191] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"409.418928ms","start":"2026-03-03T02:52:18.911974Z","end":"2026-03-03T02:52:19.321393Z","steps":["trace[980569191] 'process raft request' (duration: 405.633993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:19.321955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:18.911947Z","time spent":"409.865105ms","remote":"127.0.0.1:34030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T02:52:19.322465Z","caller":"traceutil/trace.go:171","msg":"trace[503198943] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"157.634957ms","start":"2026-03-03T02:52:19.164806Z","end":"2026-03-03T02:52:19.322441Z","steps":["trace[503198943] 'process raft request' (duration: 157.534308ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:52:19.322529Z","caller":"traceutil/trace.go:171","msg":"trace[6954326] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"357.984165ms","start":"2026-03-03T02:52:18.964485Z","end":"2026-03-03T02:52:19.322469Z","steps":["trace[6954326] 'process raft request' (duration: 357.764667ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:19.322694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:18.964451Z","time spent":"358.201794ms","remote":"127.0.0.1:33800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T02:52:22.093174Z","caller":"traceutil/trace.go:171","msg":"trace[396886998] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"100.072352ms","start":"2026-03-03T02:52:21.993075Z","end":"2026-03-03T02:52:22.093148Z","steps":["trace[396886998] 'process raft request' (duration: 99.892872ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:52:47.63936Z","caller":"traceutil/trace.go:171","msg":"trace[2056979689] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:782; }","duration":"113.537985ms","start":"2026-03-03T02:52:47.525796Z","end":"2026-03-03T02:52:47.639334Z","steps":["trace[2056979689] 'read index received' (duration: 4.721415ms)","trace[2056979689] 'applied index is now lower than readState.Index' (duration: 108.81542ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:52:47.639481Z","caller":"traceutil/trace.go:171","msg":"trace[994075166] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"115.108924ms","start":"2026-03-03T02:52:47.524339Z","end":"2026-03-03T02:52:47.639448Z","steps":["trace[994075166] 'process raft request' (duration: 92.167764ms)","trace[994075166] 'compare' (duration: 22.726687ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:52:47.639596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.781068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-03T02:52:47.639643Z","caller":"traceutil/trace.go:171","msg":"trace[510470254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:754; }","duration":"113.873459ms","start":"2026-03-03T02:52:47.525758Z","end":"2026-03-03T02:52:47.639632Z","steps":["trace[510470254] 'agreement among raft nodes before linearized reading' (duration: 113.689577ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:52:49.641302Z","caller":"traceutil/trace.go:171","msg":"trace[2080721901] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"103.448864ms","start":"2026-03-03T02:52:49.537826Z","end":"2026-03-03T02:52:49.641274Z","steps":["trace[2080721901] 'process raft request' (duration: 68.535847ms)","trace[2080721901] 'compare' (duration: 34.821056ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:10.052232Z","caller":"traceutil/trace.go:171","msg":"trace[643361460] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"132.658982ms","start":"2026-03-03T02:53:09.919545Z","end":"2026-03-03T02:53:10.052204Z","steps":["trace[643361460] 'process raft request' (duration: 59.988471ms)","trace[643361460] 'compare' (duration: 72.454496ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:15.921883Z","caller":"traceutil/trace.go:171","msg":"trace[1326670825] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"139.505838ms","start":"2026-03-03T02:53:15.782345Z","end":"2026-03-03T02:53:15.921851Z","steps":["trace[1326670825] 'process raft request' (duration: 139.209181ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:28.269455Z","caller":"traceutil/trace.go:171","msg":"trace[554051578] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"220.179937ms","start":"2026-03-03T02:53:28.04924Z","end":"2026-03-03T02:53:28.26942Z","steps":["trace[554051578] 'process raft request' (duration: 219.013144ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:30.404504Z","caller":"traceutil/trace.go:171","msg":"trace[1416728025] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"120.810059ms","start":"2026-03-03T02:53:30.283666Z","end":"2026-03-03T02:53:30.404476Z","steps":["trace[1416728025] 'process raft request' (duration: 120.673415ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:30.405196Z","caller":"traceutil/trace.go:171","msg":"trace[1110097450] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"117.331262ms","start":"2026-03-03T02:53:30.287853Z","end":"2026-03-03T02:53:30.405185Z","steps":["trace[1110097450] 'process raft request' (duration: 117.27392ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:32.660884Z","caller":"traceutil/trace.go:171","msg":"trace[1723534165] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"252.512798ms","start":"2026-03-03T02:53:32.408348Z","end":"2026-03-03T02:53:32.660861Z","steps":["trace[1723534165] 'read index received' (duration: 252.276251ms)","trace[1723534165] 'applied index is now lower than readState.Index' (duration: 235.527µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:32.661004Z","caller":"traceutil/trace.go:171","msg":"trace[353928564] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"285.619967ms","start":"2026-03-03T02:53:32.375372Z","end":"2026-03-03T02:53:32.660992Z","steps":["trace[353928564] 'process raft request' (duration: 285.348359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:32.661273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.854329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-03T02:53:32.661365Z","caller":"traceutil/trace.go:171","msg":"trace[1916701106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:887; }","duration":"253.048565ms","start":"2026-03-03T02:53:32.408299Z","end":"2026-03-03T02:53:32.661348Z","steps":["trace[1916701106] 'agreement among raft nodes before linearized reading' (duration: 252.847868ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:32.661402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.033953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-03T02:53:32.661494Z","caller":"traceutil/trace.go:171","msg":"trace[443839563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:887; }","duration":"253.174258ms","start":"2026-03-03T02:53:32.408299Z","end":"2026-03-03T02:53:32.661474Z","steps":["trace[443839563] 'agreement among raft nodes before linearized reading' (duration: 252.926351ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:32.948019Z","caller":"traceutil/trace.go:171","msg":"trace[2103892036] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"177.676013ms","start":"2026-03-03T02:53:32.770332Z","end":"2026-03-03T02:53:32.948008Z","steps":["trace[2103892036] 'process raft request' (duration: 177.499088ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:32.947962Z","caller":"traceutil/trace.go:171","msg":"trace[1976874776] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"161.356255ms","start":"2026-03-03T02:53:32.786585Z","end":"2026-03-03T02:53:32.947941Z","steps":["trace[1976874776] 'read index received' (duration: 161.196501ms)","trace[1976874776] 'applied index is now lower than readState.Index' (duration: 158.754µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:53:32.948344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.739597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:53:32.948419Z","caller":"traceutil/trace.go:171","msg":"trace[889360200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"161.858639ms","start":"2026-03-03T02:53:32.786541Z","end":"2026-03-03T02:53:32.9484Z","steps":["trace[889360200] 'agreement among raft nodes before linearized reading' (duration: 161.665214ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:33.499204Z","caller":"traceutil/trace.go:171","msg":"trace[1364925751] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"180.696936ms","start":"2026-03-03T02:53:33.318474Z","end":"2026-03-03T02:53:33.499171Z","steps":["trace[1364925751] 'process raft request' (duration: 180.424398ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:34.242829Z","caller":"traceutil/trace.go:171","msg":"trace[192746550] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"108.361222ms","start":"2026-03-03T02:53:34.13443Z","end":"2026-03-03T02:53:34.242791Z","steps":["trace[192746550] 'process raft request' (duration: 44.179299ms)","trace[192746550] 'compare' (duration: 63.978137ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:53:34.808288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.556685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-03T02:53:34.808372Z","caller":"traceutil/trace.go:171","msg":"trace[957733795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:897; }","duration":"134.652458ms","start":"2026-03-03T02:53:34.673701Z","end":"2026-03-03T02:53:34.808353Z","steps":["trace[957733795] 'range keys from in-memory index tree' (duration: 134.3609ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:35.496467Z","caller":"traceutil/trace.go:171","msg":"trace[1117455703] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:941; }","duration":"460.649003ms","start":"2026-03-03T02:53:35.035797Z","end":"2026-03-03T02:53:35.496446Z","steps":["trace[1117455703] 'read index received' (duration: 460.496079ms)","trace[1117455703] 'applied index is now lower than readState.Index' (duration: 151.934µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:35.496981Z","caller":"traceutil/trace.go:171","msg":"trace[940414769] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"547.119743ms","start":"2026-03-03T02:53:34.949397Z","end":"2026-03-03T02:53:35.496516Z","steps":["trace[940414769] 'process raft request' (duration: 546.923297ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:35.497401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:34.949382Z","time spent":"547.685219ms","remote":"127.0.0.1:33946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3854,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:53:35.497542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.718614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fd7f7195-306a-44b7-8fce-cd039c9eb65e\" ","response":"range_response_count:1 size:3869"} {"level":"info","ts":"2026-03-03T02:53:35.497651Z","caller":"traceutil/trace.go:171","msg":"trace[2117679045] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fd7f7195-306a-44b7-8fce-cd039c9eb65e; range_end:; response_count:1; response_revision:901; }","duration":"461.867678ms","start":"2026-03-03T02:53:35.035743Z","end":"2026-03-03T02:53:35.497611Z","steps":["trace[2117679045] 'agreement among raft nodes before linearized reading' (duration: 460.792377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:35.497706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:35.03572Z","time spent":"461.974421ms","remote":"127.0.0.1:33946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3892,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fd7f7195-306a-44b7-8fce-cd039c9eb65e\" "} {"level":"warn","ts":"2026-03-03T02:53:35.501639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.538905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-03T02:53:35.501705Z","caller":"traceutil/trace.go:171","msg":"trace[77187754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:901; }","duration":"415.648228ms","start":"2026-03-03T02:53:35.086041Z","end":"2026-03-03T02:53:35.501689Z","steps":["trace[77187754] 'agreement among raft nodes before linearized reading' (duration: 410.522058ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:35.501743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:35.08602Z","time spent":"415.71449ms","remote":"127.0.0.1:34030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-03T02:53:37.653273Z","caller":"traceutil/trace.go:171","msg":"trace[1912239191] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"101.454844ms","start":"2026-03-03T02:53:37.551799Z","end":"2026-03-03T02:53:37.653254Z","steps":["trace[1912239191] 'process raft request' (duration: 59.640194ms)","trace[1912239191] 'compare' (duration: 41.562962ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:41.787389Z","caller":"traceutil/trace.go:171","msg":"trace[1622861107] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"108.565677ms","start":"2026-03-03T02:53:41.678799Z","end":"2026-03-03T02:53:41.787364Z","steps":["trace[1622861107] 'process raft request' (duration: 108.382001ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:48.785289Z","caller":"traceutil/trace.go:171","msg":"trace[1964646951] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"398.322651ms","start":"2026-03-03T02:53:48.386944Z","end":"2026-03-03T02:53:48.785267Z","steps":["trace[1964646951] 'read index received' (duration: 398.087833ms)","trace[1964646951] 'applied index is now lower than readState.Index' (duration: 233.928µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:48.785543Z","caller":"traceutil/trace.go:171","msg":"trace[1350559854] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"474.575605ms","start":"2026-03-03T02:53:48.310953Z","end":"2026-03-03T02:53:48.785529Z","steps":["trace[1350559854] 'process raft request' (duration: 474.171012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:48.785685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:48.310929Z","time spent":"474.655908ms","remote":"127.0.0.1:34030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:53:48.785836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.857028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-03T02:53:48.785996Z","caller":"traceutil/trace.go:171","msg":"trace[1972426320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:958; }","duration":"399.076156ms","start":"2026-03-03T02:53:48.386903Z","end":"2026-03-03T02:53:48.78598Z","steps":["trace[1972426320] 'agreement among raft nodes before linearized reading' (duration: 398.758475ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:48.786041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:48.386889Z","time spent":"399.143298ms","remote":"127.0.0.1:34030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-03T02:53:48.785851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.049504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-03T02:53:48.786182Z","caller":"traceutil/trace.go:171","msg":"trace[1116508423] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:958; }","duration":"217.426697ms","start":"2026-03-03T02:53:48.56871Z","end":"2026-03-03T02:53:48.786137Z","steps":["trace[1116508423] 'agreement among raft nodes before linearized reading' (duration: 216.970371ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:49.132591Z","caller":"traceutil/trace.go:171","msg":"trace[1893774949] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"101.701077ms","start":"2026-03-03T02:53:49.03086Z","end":"2026-03-03T02:53:49.132561Z","steps":["trace[1893774949] 'process raft request' (duration: 39.034829ms)","trace[1893774949] 'compare' (duration: 62.523053ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:52.002698Z","caller":"traceutil/trace.go:171","msg":"trace[1622087118] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"126.779792ms","start":"2026-03-03T02:53:51.875897Z","end":"2026-03-03T02:53:52.002677Z","steps":["trace[1622087118] 'process raft request' (duration: 126.622627ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:57.075374Z","caller":"traceutil/trace.go:171","msg":"trace[1151848361] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"142.871145ms","start":"2026-03-03T02:53:56.932471Z","end":"2026-03-03T02:53:57.075342Z","steps":["trace[1151848361] 'process raft request' (duration: 76.382476ms)","trace[1151848361] 'compare' (duration: 66.328544ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:57.075527Z","caller":"traceutil/trace.go:171","msg":"trace[1131457414] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1032; }","duration":"133.746097ms","start":"2026-03-03T02:53:56.941747Z","end":"2026-03-03T02:53:57.075493Z","steps":["trace[1131457414] 'read index received' (duration: 67.047919ms)","trace[1131457414] 'applied index is now lower than readState.Index' (duration: 66.692687ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:53:57.075742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.982194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-03T02:53:57.075794Z","caller":"traceutil/trace.go:171","msg":"trace[256107790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:987; }","duration":"134.096178ms","start":"2026-03-03T02:53:56.941684Z","end":"2026-03-03T02:53:57.07578Z","steps":["trace[256107790] 'agreement among raft nodes before linearized reading' (duration: 133.938483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:57.075801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.599095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:job-controller\" ","response":"range_response_count:1 size:780"} {"level":"info","ts":"2026-03-03T02:53:57.075857Z","caller":"traceutil/trace.go:171","msg":"trace[1449113487] range","detail":"{range_begin:/registry/clusterroles/system:controller:job-controller; range_end:; response_count:1; response_revision:987; }","duration":"105.681147ms","start":"2026-03-03T02:53:56.97016Z","end":"2026-03-03T02:53:57.075842Z","steps":["trace[1449113487] 'agreement among raft nodes before linearized reading' (duration: 105.564093ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:57.076094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.735148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cilium-operator\" ","response":"range_response_count:1 size:785"} {"level":"info","ts":"2026-03-03T02:53:57.07615Z","caller":"traceutil/trace.go:171","msg":"trace[1410368412] range","detail":"{range_begin:/registry/clusterrolebindings/cilium-operator; range_end:; response_count:1; response_revision:987; }","duration":"110.953806ms","start":"2026-03-03T02:53:56.965184Z","end":"2026-03-03T02:53:57.076138Z","steps":["trace[1410368412] 'agreement among raft nodes before linearized reading' (duration: 110.47924ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:54:00.207293Z","caller":"traceutil/trace.go:171","msg":"trace[776212452] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"157.867127ms","start":"2026-03-03T02:54:00.049378Z","end":"2026-03-03T02:54:00.207245Z","steps":["trace[776212452] 'read index received' (duration: 157.772704ms)","trace[776212452] 'applied index is now lower than readState.Index' (duration: 93.363µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:54:00.207419Z","caller":"traceutil/trace.go:171","msg":"trace[120323304] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"158.938965ms","start":"2026-03-03T02:54:00.048452Z","end":"2026-03-03T02:54:00.20739Z","steps":["trace[120323304] 'process raft request' (duration: 158.632203ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:00.20749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.418937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpointslicemirroring-controller\" ","response":"range_response_count:1 size:807"} {"level":"info","ts":"2026-03-03T02:54:00.207529Z","caller":"traceutil/trace.go:171","msg":"trace[35347937] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpointslicemirroring-controller; range_end:; response_count:1; response_revision:995; }","duration":"116.4912ms","start":"2026-03-03T02:54:00.091026Z","end":"2026-03-03T02:54:00.207518Z","steps":["trace[35347937] 'agreement among raft nodes before linearized reading' (duration: 116.402317ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:00.207544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.134767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-aggregator\" ","response":"range_response_count:1 size:595"} {"level":"info","ts":"2026-03-03T02:54:00.207631Z","caller":"traceutil/trace.go:171","msg":"trace[1186602867] range","detail":"{range_begin:/registry/clusterroles/system:kube-aggregator; range_end:; response_count:1; response_revision:995; }","duration":"158.251551ms","start":"2026-03-03T02:54:00.049358Z","end":"2026-03-03T02:54:00.207609Z","steps":["trace[1186602867] 'agreement among raft nodes before linearized reading' (duration: 158.056505ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:54:02.996841Z","caller":"traceutil/trace.go:171","msg":"trace[389029498] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1048; }","duration":"111.520535ms","start":"2026-03-03T02:54:02.8853Z","end":"2026-03-03T02:54:02.996821Z","steps":["trace[389029498] 'read index received' (duration: 111.37723ms)","trace[389029498] 'applied index is now lower than readState.Index' (duration: 142.355µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:54:02.996907Z","caller":"traceutil/trace.go:171","msg":"trace[612318431] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"123.513222ms","start":"2026-03-03T02:54:02.873371Z","end":"2026-03-03T02:54:02.996884Z","steps":["trace[612318431] 'process raft request' (duration: 123.325826ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:02.996977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.65959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:statefulset-controller\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-03-03T02:54:02.997043Z","caller":"traceutil/trace.go:171","msg":"trace[1875301150] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:statefulset-controller; range_end:; response_count:1; response_revision:1002; }","duration":"111.763624ms","start":"2026-03-03T02:54:02.885264Z","end":"2026-03-03T02:54:02.997027Z","steps":["trace[1875301150] 'agreement among raft nodes before linearized reading' (duration: 111.64672ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:54:20.55481Z","caller":"traceutil/trace.go:171","msg":"trace[1432234636] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"123.995361ms","start":"2026-03-03T02:54:20.430796Z","end":"2026-03-03T02:54:20.554792Z","steps":["trace[1432234636] 'read index received' (duration: 123.823745ms)","trace[1432234636] 'applied index is now lower than readState.Index' (duration: 171.046µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:54:20.554889Z","caller":"traceutil/trace.go:171","msg":"trace[778207514] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"167.788478ms","start":"2026-03-03T02:54:20.387066Z","end":"2026-03-03T02:54:20.554855Z","steps":["trace[778207514] 'process raft request' (duration: 167.613431ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:20.555101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.276603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-lvn5x\" ","response":"range_response_count:1 size:4905"} {"level":"info","ts":"2026-03-03T02:54:20.555215Z","caller":"traceutil/trace.go:171","msg":"trace[486453718] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-lvn5x; range_end:; response_count:1; response_revision:1052; }","duration":"124.407587ms","start":"2026-03-03T02:54:20.430764Z","end":"2026-03-03T02:54:20.555172Z","steps":["trace[486453718] 'agreement among raft nodes before linearized reading' (duration: 124.111086ms)"],"step_count":1}