{"level":"warn","ts":"2026-03-05T07:21:42.225723Z","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-05T07:21:42.225819Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-05T07:21:42.225914Z","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-05T07:21:42.225928Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-03-05T07:21:42.22599Z","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-05T07:21:42.226789Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-03-05T07:21:42.227001Z","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.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-05T07:21:42.231363Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.048632ms"} {"level":"info","ts":"2026-03-05T07:21:42.238896Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-03-05T07:21:42.238977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-03-05T07:21:42.238999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-03-05T07:21:42.239099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-05T07:21:42.239121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-03-05T07:21:42.239153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-03-05T07:21:42.258973Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-05T07:21:42.260545Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-05T07:21:42.261809Z","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-05T07:21:42.265524Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-05T07:21:42.26565Z","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-05T07:21:42.265749Z","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-05T07:21:42.26576Z","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-05T07:21:42.265753Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-05T07:21:42.267518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-03-05T07:21:42.268023Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-03-05T07:21:42.269306Z","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-05T07:21:42.269502Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-03-05T07:21:42.269539Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-03-05T07:21:42.270136Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-05T07:21:42.27017Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-05T07:21:42.340433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-03-05T07:21:42.340653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-03-05T07:21:42.340685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-03-05T07:21:42.340817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-03-05T07:21:42.340838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-03-05T07:21:42.340941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-03-05T07:21:42.340965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-03-05T07:21:42.341958Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-03-05T07:21:42.341956Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T07:21:42.342002Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T07:21:42.342138Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T07:21:42.34542Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-03-05T07:21:42.345644Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-05T07:21:42.34574Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-05T07:21:42.345804Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-05T07:21:42.346426Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T07:21:42.346525Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T07:21:42.346553Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T07:21:46.351253Z","caller":"traceutil/trace.go:171","msg":"trace[240805752] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"354.462619ms","start":"2026-03-05T07:21:45.996762Z","end":"2026-03-05T07:21:46.351225Z","steps":["trace[240805752] 'process raft request' (duration: 354.309036ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:21:46.352062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:21:45.996726Z","time spent":"354.627484ms","remote":"127.0.0.1:43220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:21:47.705525Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.354365855s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-05T07:21:49.098933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.392811317s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-03-05T07:21:49.099176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:21:47.580397Z","time spent":"1.518776329s","remote":"127.0.0.1:43018","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-05T07:21:49.099373Z","caller":"traceutil/trace.go:171","msg":"trace[220286669] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"3.1025391s","start":"2026-03-05T07:21:45.996816Z","end":"2026-03-05T07:21:49.099355Z","steps":["trace[220286669] 'process raft request' (duration: 1.709032579s)","trace[220286669] 'compare' (duration: 1.392518821s)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:21:49.099439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:21:45.996783Z","time spent":"3.102622052s","remote":"127.0.0.1:43220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T07:21:49.099468Z","caller":"traceutil/trace.go:171","msg":"trace[1717547500] linearizableReadLoop","detail":"{readStateIndex:214; appliedIndex:212; }","duration":"2.960531903s","start":"2026-03-05T07:21:46.138923Z","end":"2026-03-05T07:21:49.099455Z","steps":["trace[1717547500] 'read index received' (duration: 212.225938ms)","trace[1717547500] 'applied index is now lower than readState.Index' (duration: 2.748304605s)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:21:49.099535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.960621725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-03-05T07:21:49.099553Z","caller":"traceutil/trace.go:171","msg":"trace[411561339] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:209; }","duration":"2.960687036s","start":"2026-03-05T07:21:46.138858Z","end":"2026-03-05T07:21:49.099545Z","steps":["trace[411561339] 'agreement among raft nodes before linearized reading' (duration: 2.960647285s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:21:49.09957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:21:46.138832Z","time spent":"2.960734148s","remote":"127.0.0.1:43138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3514,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-05T07:21:49.155375Z","caller":"traceutil/trace.go:171","msg":"trace[1674378277] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"1.224819799s","start":"2026-03-05T07:21:47.930514Z","end":"2026-03-05T07:21:49.155334Z","steps":["trace[1674378277] 'process raft request' (duration: 1.224702127s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:21:49.155502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.566533ms","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-03-05T07:21:49.155401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.552001894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:21:49.155587Z","caller":"traceutil/trace.go:171","msg":"trace[1689052040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:210; }","duration":"799.699917ms","start":"2026-03-05T07:21:48.355868Z","end":"2026-03-05T07:21:49.155568Z","steps":["trace[1689052040] 'agreement among raft nodes before linearized reading' (duration: 799.523902ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:21:49.1556Z","caller":"traceutil/trace.go:171","msg":"trace[1360384801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:210; }","duration":"2.55224732s","start":"2026-03-05T07:21:46.603328Z","end":"2026-03-05T07:21:49.155576Z","steps":["trace[1360384801] 'agreement among raft nodes before linearized reading' (duration: 2.551996414s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:21:49.155666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:21:46.603302Z","time spent":"2.552350751s","remote":"127.0.0.1:43220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-05T07:21:49.155663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:21:48.355852Z","time spent":"799.801338ms","remote":"127.0.0.1:43220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-05T07:21:49.155839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032024761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:21:49.155934Z","caller":"traceutil/trace.go:171","msg":"trace[1430580832] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:210; }","duration":"1.032182884s","start":"2026-03-05T07:21:48.123729Z","end":"2026-03-05T07:21:49.155912Z","steps":["trace[1430580832] 'agreement among raft nodes before linearized reading' (duration: 1.031700764s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:21:49.156041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:21:48.12371Z","time spent":"1.032318876s","remote":"127.0.0.1:43220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-05T07:21:49.156113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:21:47.930486Z","time spent":"1.225046064s","remote":"127.0.0.1:43148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T07:21:50.068098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.417557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-05T07:21:50.068477Z","caller":"traceutil/trace.go:171","msg":"trace[438568249] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"205.358109ms","start":"2026-03-05T07:21:49.862952Z","end":"2026-03-05T07:21:50.06831Z","steps":["trace[438568249] 'process raft request' (duration: 205.288988ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:21:50.068405Z","caller":"traceutil/trace.go:171","msg":"trace[2087305938] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"207.39472ms","start":"2026-03-05T07:21:49.860956Z","end":"2026-03-05T07:21:50.06835Z","steps":["trace[2087305938] 'process raft request' (duration: 52.613696ms)","trace[2087305938] 'compare' (duration: 154.193673ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:21:50.448939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.846867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-05T07:21:50.449079Z","caller":"traceutil/trace.go:171","msg":"trace[402836601] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"234.553117ms","start":"2026-03-05T07:21:50.214496Z","end":"2026-03-05T07:21:50.449049Z","steps":["trace[402836601] 'process raft request' (duration: 38.511976ms)","trace[402836601] 'compare' (duration: 195.691164ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:21:51.580165Z","caller":"traceutil/trace.go:171","msg":"trace[1092055873] transaction","detail":"{read_only:false; number_of_response:0; response_revision:321; }","duration":"114.247949ms","start":"2026-03-05T07:21:51.465887Z","end":"2026-03-05T07:21:51.580135Z","steps":["trace[1092055873] 'process raft request' (duration: 114.102197ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:21:51.593569Z","caller":"traceutil/trace.go:171","msg":"trace[567099111] transaction","detail":"{read_only:false; number_of_response:0; response_revision:321; }","duration":"127.255094ms","start":"2026-03-05T07:21:51.466279Z","end":"2026-03-05T07:21:51.593534Z","steps":["trace[567099111] 'process raft request' (duration: 127.02719ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:21:51.593595Z","caller":"traceutil/trace.go:171","msg":"trace[42751357] transaction","detail":"{read_only:false; number_of_response:0; response_revision:321; }","duration":"127.144083ms","start":"2026-03-05T07:21:51.46642Z","end":"2026-03-05T07:21:51.593564Z","steps":["trace[42751357] 'process raft request' (duration: 127.00361ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:21:51.594338Z","caller":"traceutil/trace.go:171","msg":"trace[1076733967] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"101.57803ms","start":"2026-03-05T07:21:51.492058Z","end":"2026-03-05T07:21:51.593636Z","steps":["trace[1076733967] 'process raft request' (duration: 101.423937ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:21:51.594408Z","caller":"traceutil/trace.go:171","msg":"trace[1392181847] transaction","detail":"{read_only:false; number_of_response:0; response_revision:321; }","duration":"127.486979ms","start":"2026-03-05T07:21:51.466892Z","end":"2026-03-05T07:21:51.594379Z","steps":["trace[1392181847] 'process raft request' (duration: 126.561231ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:21:51.731599Z","caller":"traceutil/trace.go:171","msg":"trace[1311965335] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:335; }","duration":"118.785298ms","start":"2026-03-05T07:21:51.612779Z","end":"2026-03-05T07:21:51.731564Z","steps":["trace[1311965335] 'read index received' (duration: 18.725876ms)","trace[1311965335] 'applied index is now lower than readState.Index' (duration: 100.058292ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:21:51.731702Z","caller":"traceutil/trace.go:171","msg":"trace[1729295581] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"121.696885ms","start":"2026-03-05T07:21:51.609963Z","end":"2026-03-05T07:21:51.731666Z","steps":["trace[1729295581] 'process raft request' (duration: 21.633353ms)","trace[1729295581] 'compare' (duration: 99.819247ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:21:51.731789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.972731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-05T07:21:51.731838Z","caller":"traceutil/trace.go:171","msg":"trace[1139624144] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:323; }","duration":"119.057353ms","start":"2026-03-05T07:21:51.612763Z","end":"2026-03-05T07:21:51.73182Z","steps":["trace[1139624144] 'agreement among raft nodes before linearized reading' (duration: 118.94548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:21:51.998925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.085409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-03-05T07:21:51.998973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.613988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-03-05T07:21:51.999028Z","caller":"traceutil/trace.go:171","msg":"trace[1397334043] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:326; }","duration":"134.271662ms","start":"2026-03-05T07:21:51.864732Z","end":"2026-03-05T07:21:51.999004Z","steps":["trace[1397334043] 'range keys from in-memory index tree' (duration: 133.970035ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:21:51.999065Z","caller":"traceutil/trace.go:171","msg":"trace[1724703419] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:326; }","duration":"165.783401ms","start":"2026-03-05T07:21:51.83326Z","end":"2026-03-05T07:21:51.999043Z","steps":["trace[1724703419] 'range keys from in-memory index tree' (duration: 165.462995ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:21:52.355532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.676837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T07:21:52.355728Z","caller":"traceutil/trace.go:171","msg":"trace[245729755] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:340; }","duration":"311.487122ms","start":"2026-03-05T07:21:52.044218Z","end":"2026-03-05T07:21:52.355705Z","steps":["trace[245729755] 'read index received' (duration: 181.576771ms)","trace[245729755] 'applied index is now lower than readState.Index' (duration: 129.908691ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:21:52.355852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.634135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:21:52.355911Z","caller":"traceutil/trace.go:171","msg":"trace[1466947914] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:0; response_revision:328; }","duration":"311.738377ms","start":"2026-03-05T07:21:52.044159Z","end":"2026-03-05T07:21:52.355897Z","steps":["trace[1466947914] 'agreement among raft nodes before linearized reading' (duration: 311.634815ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:21:52.355923Z","caller":"traceutil/trace.go:171","msg":"trace[1392260675] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"348.788102ms","start":"2026-03-05T07:21:52.007106Z","end":"2026-03-05T07:21:52.355894Z","steps":["trace[1392260675] 'process raft request' (duration: 218.639026ms)","trace[1392260675] 'compare' (duration: 129.524294ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:21:52.355977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:21:52.044136Z","time spent":"311.828349ms","remote":"127.0.0.1:43166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-03-05T07:21:52.356081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:21:52.007093Z","time spent":"348.901544ms","remote":"127.0.0.1:43148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:21:54.782292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.114797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T07:21:54.782542Z","caller":"traceutil/trace.go:171","msg":"trace[1748020409] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:376; }","duration":"468.446684ms","start":"2026-03-05T07:21:54.314075Z","end":"2026-03-05T07:21:54.782522Z","steps":["trace[1748020409] 'read index received' (duration: 16.90836ms)","trace[1748020409] 'applied index is now lower than readState.Index' (duration: 451.536934ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:21:54.78266Z","caller":"traceutil/trace.go:171","msg":"trace[2041513058] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"490.620266ms","start":"2026-03-05T07:21:54.292027Z","end":"2026-03-05T07:21:54.782647Z","steps":["trace[2041513058] 'process raft request' (duration: 39.0495ms)","trace[2041513058] 'compare' (duration: 450.949124ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:21:54.782756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:21:54.292013Z","time spent":"490.686238ms","remote":"127.0.0.1:43444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:21:54.783043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.520253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-05T07:21:54.783081Z","caller":"traceutil/trace.go:171","msg":"trace[791522265] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:365; }","duration":"318.607885ms","start":"2026-03-05T07:21:54.464463Z","end":"2026-03-05T07:21:54.78307Z","steps":["trace[791522265] 'agreement among raft nodes before linearized reading' (duration: 318.448022ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:21:54.784022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.478181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T07:21:54.78408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.400115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-03-05T07:21:54.784142Z","caller":"traceutil/trace.go:171","msg":"trace[1569672822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:365; }","duration":"132.639414ms","start":"2026-03-05T07:21:54.651478Z","end":"2026-03-05T07:21:54.784117Z","steps":["trace[1569672822] 'agreement among raft nodes before linearized reading' (duration: 132.44424ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:21:54.784209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.46801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-05T07:21:54.784278Z","caller":"traceutil/trace.go:171","msg":"trace[2020409581] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:365; }","duration":"369.611389ms","start":"2026-03-05T07:21:54.414564Z","end":"2026-03-05T07:21:54.784175Z","steps":["trace[2020409581] 'agreement among raft nodes before linearized reading' (duration: 368.369604ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:21:54.784362Z","caller":"traceutil/trace.go:171","msg":"trace[1919513516] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:365; }","duration":"420.659644ms","start":"2026-03-05T07:21:54.363647Z","end":"2026-03-05T07:21:54.784307Z","steps":["trace[1919513516] 'agreement among raft nodes before linearized reading' (duration: 419.268206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:21:54.784408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:21:54.414536Z","time spent":"369.855554ms","remote":"127.0.0.1:43166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-03-05T07:21:54.784435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:21:54.363598Z","time spent":"420.822707ms","remote":"127.0.0.1:43166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-03-05T07:21:54.785035Z","caller":"traceutil/trace.go:171","msg":"trace[240571581] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"492.34906ms","start":"2026-03-05T07:21:54.292668Z","end":"2026-03-05T07:21:54.785017Z","steps":["trace[240571581] 'process raft request' (duration: 489.77228ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:21:54.785052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:21:54.464431Z","time spent":"318.674727ms","remote":"127.0.0.1:43166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-03-05T07:21:54.785195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:21:54.292648Z","time spent":"492.430411ms","remote":"127.0.0.1:43148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:21:54.792146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.076902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-05T07:21:54.792198Z","caller":"traceutil/trace.go:171","msg":"trace[1804137824] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:365; }","duration":"478.175654ms","start":"2026-03-05T07:21:54.314009Z","end":"2026-03-05T07:21:54.792185Z","steps":["trace[1804137824] 'agreement among raft nodes before linearized reading' (duration: 468.684499ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:21:54.792239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:21:54.31399Z","time spent":"478.233155ms","remote":"127.0.0.1:43166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"info","ts":"2026-03-05T07:21:55.391963Z","caller":"traceutil/trace.go:171","msg":"trace[793733693] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"101.871881ms","start":"2026-03-05T07:21:55.290056Z","end":"2026-03-05T07:21:55.391928Z","steps":["trace[793733693] 'process raft request' (duration: 11.284949ms)","trace[793733693] 'compare' (duration: 90.417029ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:21:55.964075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.023885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-03-05T07:21:55.964181Z","caller":"traceutil/trace.go:171","msg":"trace[1541567931] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:397; }","duration":"152.189988ms","start":"2026-03-05T07:21:55.811967Z","end":"2026-03-05T07:21:55.964157Z","steps":["trace[1541567931] 'range keys from in-memory index tree' (duration: 151.869052ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:22:29.341503Z","caller":"traceutil/trace.go:171","msg":"trace[479686851] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"118.692476ms","start":"2026-03-05T07:22:29.222787Z","end":"2026-03-05T07:22:29.341479Z","steps":["trace[479686851] 'process raft request' (duration: 118.559323ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:22:29.687156Z","caller":"traceutil/trace.go:171","msg":"trace[1788336915] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:503; }","duration":"318.086375ms","start":"2026-03-05T07:22:29.369034Z","end":"2026-03-05T07:22:29.68712Z","steps":["trace[1788336915] 'read index received' (duration: 312.511221ms)","trace[1788336915] 'applied index is now lower than readState.Index' (duration: 5.574014ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:22:29.687342Z","caller":"traceutil/trace.go:171","msg":"trace[7711417] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"336.340726ms","start":"2026-03-05T07:22:29.35097Z","end":"2026-03-05T07:22:29.68731Z","steps":["trace[7711417] 'process raft request' (duration: 330.545538ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:29.68749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.468637ms","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-03-05T07:22:29.687637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.687621ms","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-03-05T07:22:29.68773Z","caller":"traceutil/trace.go:171","msg":"trace[1098327437] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:483; }","duration":"247.836794ms","start":"2026-03-05T07:22:29.439875Z","end":"2026-03-05T07:22:29.687712Z","steps":["trace[1098327437] 'agreement among raft nodes before linearized reading' (duration: 247.64074ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:29.687734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.766926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-05T07:22:29.687808Z","caller":"traceutil/trace.go:171","msg":"trace[1983876555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:483; }","duration":"255.630779ms","start":"2026-03-05T07:22:29.431946Z","end":"2026-03-05T07:22:29.687577Z","steps":["trace[1983876555] 'agreement among raft nodes before linearized reading' (duration: 255.418515ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:22:29.687832Z","caller":"traceutil/trace.go:171","msg":"trace[1192010457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:483; }","duration":"148.870978ms","start":"2026-03-05T07:22:29.538927Z","end":"2026-03-05T07:22:29.687798Z","steps":["trace[1192010457] 'agreement among raft nodes before linearized reading' (duration: 148.657484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:29.688162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:22:29.350955Z","time spent":"336.455578ms","remote":"127.0.0.1:43460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T07:22:29.687882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.381421ms","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-05T07:22:29.688896Z","caller":"traceutil/trace.go:171","msg":"trace[106401693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:483; }","duration":"319.241257ms","start":"2026-03-05T07:22:29.368972Z","end":"2026-03-05T07:22:29.688213Z","steps":["trace[106401693] 'agreement among raft nodes before linearized reading' (duration: 318.33806ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:29.688992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:22:29.368942Z","time spent":"320.028643ms","remote":"127.0.0.1:43220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-05T07:22:43.693555Z","caller":"traceutil/trace.go:171","msg":"trace[1093896515] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"420.62705ms","start":"2026-03-05T07:22:43.272907Z","end":"2026-03-05T07:22:43.693534Z","steps":["trace[1093896515] 'process raft request' (duration: 420.454736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:43.693824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:22:43.272865Z","time spent":"420.876977ms","remote":"127.0.0.1:43138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4566,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T07:22:49.934412Z","caller":"traceutil/trace.go:171","msg":"trace[2119984099] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"144.375142ms","start":"2026-03-05T07:22:49.790014Z","end":"2026-03-05T07:22:49.934389Z","steps":["trace[2119984099] 'read index received' (duration: 144.272899ms)","trace[2119984099] 'applied index is now lower than readState.Index' (duration: 101.272µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:22:49.934474Z","caller":"traceutil/trace.go:171","msg":"trace[1744358591] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"252.858556ms","start":"2026-03-05T07:22:49.681572Z","end":"2026-03-05T07:22:49.93443Z","steps":["trace[1744358591] 'process raft request' (duration: 252.675041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:49.934758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.708951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-05T07:22:49.935Z","caller":"traceutil/trace.go:171","msg":"trace[49514573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"144.955757ms","start":"2026-03-05T07:22:49.789967Z","end":"2026-03-05T07:22:49.934923Z","steps":["trace[49514573] 'agreement among raft nodes before linearized reading' (duration: 144.530565ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:49.935146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.795477ms","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-03-05T07:22:49.93526Z","caller":"traceutil/trace.go:171","msg":"trace[1513890819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"110.952331ms","start":"2026-03-05T07:22:49.824293Z","end":"2026-03-05T07:22:49.935245Z","steps":["trace[1513890819] 'agreement among raft nodes before linearized reading' (duration: 110.337905ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:22:50.71234Z","caller":"traceutil/trace.go:171","msg":"trace[1708657281] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"768.609551ms","start":"2026-03-05T07:22:49.943705Z","end":"2026-03-05T07:22:50.712315Z","steps":["trace[1708657281] 'process raft request' (duration: 768.537139ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:22:50.712394Z","caller":"traceutil/trace.go:171","msg":"trace[685440321] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"772.422754ms","start":"2026-03-05T07:22:49.939927Z","end":"2026-03-05T07:22:50.712348Z","steps":["trace[685440321] 'process raft request' (duration: 772.159967ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:22:50.712454Z","caller":"traceutil/trace.go:171","msg":"trace[9956857] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:645; }","duration":"441.509467ms","start":"2026-03-05T07:22:50.270928Z","end":"2026-03-05T07:22:50.712438Z","steps":["trace[9956857] 'read index received' (duration: 441.084346ms)","trace[9956857] 'applied index is now lower than readState.Index' (duration: 424.001µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:22:50.712501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:22:49.943683Z","time spent":"768.739735ms","remote":"127.0.0.1:43220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:22:50.712527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.589369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:22:50.71255Z","caller":"traceutil/trace.go:171","msg":"trace[1942543431] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:622; }","duration":"441.676452ms","start":"2026-03-05T07:22:50.270867Z","end":"2026-03-05T07:22:50.712543Z","steps":["trace[1942543431] 'agreement among raft nodes before linearized reading' (duration: 441.622581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:50.712573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:22:50.270836Z","time spent":"441.730993ms","remote":"127.0.0.1:43096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-03-05T07:22:50.712575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:22:49.939915Z","time spent":"772.574129ms","remote":"127.0.0.1:43220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T07:22:50.712684Z","caller":"traceutil/trace.go:171","msg":"trace[490066277] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"768.56663ms","start":"2026-03-05T07:22:49.944089Z","end":"2026-03-05T07:22:50.712655Z","steps":["trace[490066277] 'process raft request' (duration: 768.192ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:50.712832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:22:49.944054Z","time spent":"768.699135ms","remote":"127.0.0.1:43220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:22:51.850676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.784945ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-05T07:22:51.850994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.605065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:22:51.851091Z","caller":"traceutil/trace.go:171","msg":"trace[1731887374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"919.758119ms","start":"2026-03-05T07:22:50.931311Z","end":"2026-03-05T07:22:51.851069Z","steps":["trace[1731887374] 'agreement among raft nodes before linearized reading' (duration: 919.581004ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:51.851142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:22:50.93129Z","time spent":"919.844571ms","remote":"127.0.0.1:42922","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-05T07:22:51.851083Z","caller":"traceutil/trace.go:171","msg":"trace[1677264814] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"919.499072ms","start":"2026-03-05T07:22:50.931367Z","end":"2026-03-05T07:22:51.850866Z","steps":["trace[1677264814] 'read index received' (duration: 136.508631ms)","trace[1677264814] 'applied index is now lower than readState.Index' (duration: 782.987911ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:22:52.166643Z","caller":"traceutil/trace.go:171","msg":"trace[1467360087] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"211.654753ms","start":"2026-03-05T07:22:51.95497Z","end":"2026-03-05T07:22:52.166624Z","steps":["trace[1467360087] 'process raft request' (duration: 211.526758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:53.142081Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120168683725936,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-05T07:22:53.642983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120168683725936,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-05T07:22:54.143315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120168683725936,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-05T07:22:54.614883Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.045135339s","expected-duration":"1s"} {"level":"info","ts":"2026-03-05T07:22:54.615158Z","caller":"traceutil/trace.go:171","msg":"trace[2068521377] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"2.045498439s","start":"2026-03-05T07:22:52.569643Z","end":"2026-03-05T07:22:54.615141Z","steps":["trace[2068521377] 'process raft request' (duration: 2.045381075s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:54.615255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:22:52.569619Z","time spent":"2.045574621s","remote":"127.0.0.1:43138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4744,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T07:22:54.615245Z","caller":"traceutil/trace.go:171","msg":"trace[217883003] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:652; }","duration":"1.973491864s","start":"2026-03-05T07:22:52.641708Z","end":"2026-03-05T07:22:54.6152Z","steps":["trace[217883003] 'read index received' (duration: 1.973475244s)","trace[217883003] 'applied index is now lower than readState.Index' (duration: 13.94µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:22:54.615403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.973663589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:22:54.615486Z","caller":"traceutil/trace.go:171","msg":"trace[1790850957] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:625; }","duration":"1.973812982s","start":"2026-03-05T07:22:52.641653Z","end":"2026-03-05T07:22:54.615466Z","steps":["trace[1790850957] 'agreement among raft nodes before linearized reading' (duration: 1.973638988s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:54.61554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:22:52.641628Z","time spent":"1.973897245s","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-03-05T07:22:54.655765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.939319146s","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-05T07:22:54.655855Z","caller":"traceutil/trace.go:171","msg":"trace[1206466077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"1.939459399s","start":"2026-03-05T07:22:52.716369Z","end":"2026-03-05T07:22:54.655829Z","steps":["trace[1206466077] 'agreement among raft nodes before linearized reading' (duration: 1.939140341s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:54.655896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:22:52.716289Z","time spent":"1.939599174s","remote":"127.0.0.1:43220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-05T07:22:54.656245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.939534241s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-05T07:22:54.656299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.939588452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-05T07:22:54.656325Z","caller":"traceutil/trace.go:171","msg":"trace[1086053330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"1.939658405s","start":"2026-03-05T07:22:52.716649Z","end":"2026-03-05T07:22:54.656307Z","steps":["trace[1086053330] 'agreement among raft nodes before linearized reading' (duration: 1.939410528s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:54.656373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:22:52.716638Z","time spent":"1.939727766s","remote":"127.0.0.1:43220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-05T07:22:54.656371Z","caller":"traceutil/trace.go:171","msg":"trace[1469752059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"1.939747437s","start":"2026-03-05T07:22:52.716605Z","end":"2026-03-05T07:22:54.656352Z","steps":["trace[1469752059] 'agreement among raft nodes before linearized reading' (duration: 1.93948414s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:54.656425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:22:52.71659Z","time spent":"1.939825429s","remote":"127.0.0.1:43220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-05T07:22:54.65625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.724153624s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:22:54.656486Z","caller":"traceutil/trace.go:171","msg":"trace[351812493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"1.724443162s","start":"2026-03-05T07:22:52.93203Z","end":"2026-03-05T07:22:54.656473Z","steps":["trace[351812493] 'agreement among raft nodes before linearized reading' (duration: 1.724158894s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:54.656534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:22:52.932003Z","time spent":"1.724518574s","remote":"127.0.0.1:42922","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-05T07:22:54.656594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.83578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-05T07:22:54.656637Z","caller":"traceutil/trace.go:171","msg":"trace[607271679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"485.898792ms","start":"2026-03-05T07:22:54.170727Z","end":"2026-03-05T07:22:54.656626Z","steps":["trace[607271679] 'agreement among raft nodes before linearized reading' (duration: 485.256235ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:54.656667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:22:54.170715Z","time spent":"485.945633ms","remote":"127.0.0.1:43220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-05T07:22:56.264318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.327605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:22:56.264378Z","caller":"traceutil/trace.go:171","msg":"trace[1568142625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"332.458028ms","start":"2026-03-05T07:22:55.931908Z","end":"2026-03-05T07:22:56.264366Z","steps":["trace[1568142625] 'range keys from in-memory index tree' (duration: 332.213011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:22:56.264409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:22:55.931885Z","time spent":"332.515719ms","remote":"127.0.0.1:42922","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-05T07:24:25.142982Z","caller":"traceutil/trace.go:171","msg":"trace[618565432] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"105.248173ms","start":"2026-03-05T07:24:25.037711Z","end":"2026-03-05T07:24:25.14296Z","steps":["trace[618565432] 'process raft request' (duration: 104.962447ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:24:25.437922Z","caller":"traceutil/trace.go:171","msg":"trace[136425703] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"290.961254ms","start":"2026-03-05T07:24:25.146914Z","end":"2026-03-05T07:24:25.437875Z","steps":["trace[136425703] 'process raft request' (duration: 276.981859ms)","trace[136425703] 'compare' (duration: 13.791121ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:24:25.480091Z","caller":"traceutil/trace.go:171","msg":"trace[948248454] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"332.224914ms","start":"2026-03-05T07:24:25.14784Z","end":"2026-03-05T07:24:25.480065Z","steps":["trace[948248454] 'process raft request' (duration: 332.115512ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:24:25.480173Z","caller":"traceutil/trace.go:171","msg":"trace[1766080059] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"332.053871ms","start":"2026-03-05T07:24:25.148038Z","end":"2026-03-05T07:24:25.480092Z","steps":["trace[1766080059] 'process raft request' (duration: 331.946128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:24:25.480321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:24:25.147828Z","time spent":"332.418699ms","remote":"127.0.0.1:43220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:24:25.480351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:24:25.148013Z","time spent":"332.249735ms","remote":"127.0.0.1:43220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T07:24:25.480061Z","caller":"traceutil/trace.go:171","msg":"trace[1299462780] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"332.741415ms","start":"2026-03-05T07:24:25.147283Z","end":"2026-03-05T07:24:25.480025Z","steps":["trace[1299462780] 'process raft request' (duration: 332.536121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:24:25.48085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:24:25.147247Z","time spent":"333.485472ms","remote":"127.0.0.1:43220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}