{"level":"warn","ts":"2026-02-05T15:14:41.494134Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T15:14:41.494319Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144: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.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-05T15:14:41.494682Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T15:14:41.494698Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-02-05T15:14:41.494828Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-05T15:14:41.495664Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-02-05T15:14:41.495868Z","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":2,"max-cpu-available":2,"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.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-05T15:14:41.499983Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.779738ms"} {"level":"info","ts":"2026-02-05T15:14:41.548373Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-02-05T15:14:41.548442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T15:14:41.548489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-02-05T15:14:41.548501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T15:14:41.548533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-02-05T15:14:41.548672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-02-05T15:14:41.55559Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T15:14:41.557205Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T15:14:41.55851Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-05T15:14:41.560789Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T15:14:41.560896Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-05T15:14:41.560939Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-05T15:14:41.560969Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-05T15:14:41.561421Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T15:14:41.562145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-02-05T15:14:41.562288Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-02-05T15:14:41.578927Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-05T15:14:41.583326Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T15:14:41.583376Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T15:14:41.581767Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-02-05T15:14:41.584981Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-02-05T15:14:42.549807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T15:14:42.549846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T15:14:42.549863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-02-05T15:14:42.549873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-02-05T15:14:42.549878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-02-05T15:14:42.549886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-02-05T15:14:42.549896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-02-05T15:14:42.553315Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T15:14:42.553403Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T15:14:42.55366Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T15:14:42.557064Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T15:14:42.55755Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T15:14:42.557583Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T15:14:42.560123Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T15:14:42.61702Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T15:14:42.617087Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T15:14:42.617107Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T15:14:42.637563Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-02-05T15:14:57.658125Z","caller":"traceutil/trace.go:171","msg":"trace[1419904367] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:378; }","duration":"188.036755ms","start":"2026-02-05T15:14:57.470075Z","end":"2026-02-05T15:14:57.658112Z","steps":["trace[1419904367] 'read index received' (duration: 187.903464ms)","trace[1419904367] 'applied index is now lower than readState.Index' (duration: 132.591µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:14:57.658341Z","caller":"traceutil/trace.go:171","msg":"trace[166530891] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"404.557275ms","start":"2026-02-05T15:14:57.253775Z","end":"2026-02-05T15:14:57.658332Z","steps":["trace[166530891] 'process raft request' (duration: 404.251223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:14:57.658681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:14:57.253753Z","time spent":"404.608615ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:14:57.658816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.7433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T15:14:57.658843Z","caller":"traceutil/trace.go:171","msg":"trace[1879093679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:369; }","duration":"188.79632ms","start":"2026-02-05T15:14:57.470039Z","end":"2026-02-05T15:14:57.658835Z","steps":["trace[1879093679] 'agreement among raft nodes before linearized reading' (duration: 188.75226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:14:58.979513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.495938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T15:14:58.979622Z","caller":"traceutil/trace.go:171","msg":"trace[223849026] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"1.31513719s","start":"2026-02-05T15:14:57.664438Z","end":"2026-02-05T15:14:58.979575Z","steps":["trace[223849026] 'process raft request' (duration: 793.33591ms)","trace[223849026] 'compare' (duration: 520.27917ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:14:58.979883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:14:57.664419Z","time spent":"1.315314571s","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:14:59.1235Z","caller":"traceutil/trace.go:171","msg":"trace[1210863849] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:379; }","duration":"980.490986ms","start":"2026-02-05T15:14:58.142978Z","end":"2026-02-05T15:14:59.123469Z","steps":["trace[1210863849] 'read index received' (duration: 314.803148ms)","trace[1210863849] 'applied index is now lower than readState.Index' (duration: 665.686978ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:14:59.123944Z","caller":"traceutil/trace.go:171","msg":"trace[1787543665] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"1.448412618s","start":"2026-02-05T15:14:57.675521Z","end":"2026-02-05T15:14:59.123934Z","steps":["trace[1787543665] 'process raft request' (duration: 1.426470346s)","trace[1787543665] 'compare' (duration: 21.357898ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:14:59.124068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:14:57.675492Z","time spent":"1.448483018s","remote":"127.0.0.1:60414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4005,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:14:59.124342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.358441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-05T15:14:59.124373Z","caller":"traceutil/trace.go:171","msg":"trace[264424298] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:371; }","duration":"981.427012ms","start":"2026-02-05T15:14:58.142938Z","end":"2026-02-05T15:14:59.124366Z","steps":["trace[264424298] 'agreement among raft nodes before linearized reading' (duration: 981.278241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:14:59.124394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:14:58.14292Z","time spent":"981.469612ms","remote":"127.0.0.1:60426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6921,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-05T15:14:59.124581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.877063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T15:14:59.124606Z","caller":"traceutil/trace.go:171","msg":"trace[556019979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:371; }","duration":"959.935823ms","start":"2026-02-05T15:14:58.164664Z","end":"2026-02-05T15:14:59.1246Z","steps":["trace[556019979] 'agreement among raft nodes before linearized reading' (duration: 959.884163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:14:59.124626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:14:58.164646Z","time spent":"959.976183ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T15:14:59.12482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.166031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T15:14:59.124846Z","caller":"traceutil/trace.go:171","msg":"trace[1488010] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:371; }","duration":"697.224651ms","start":"2026-02-05T15:14:58.427615Z","end":"2026-02-05T15:14:59.124839Z","steps":["trace[1488010] 'agreement among raft nodes before linearized reading' (duration: 697.183761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:14:59.124863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:14:58.427594Z","time spent":"697.265432ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T15:14:59.124954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.885811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:14:59.12497Z","caller":"traceutil/trace.go:171","msg":"trace[769246849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"951.920261ms","start":"2026-02-05T15:14:58.173045Z","end":"2026-02-05T15:14:59.124965Z","steps":["trace[769246849] 'agreement among raft nodes before linearized reading' (duration: 951.892091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:14:59.124986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:14:58.173036Z","time spent":"951.947251ms","remote":"127.0.0.1:60226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T15:14:59.125084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.744156ms","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-02-05T15:14:59.1251Z","caller":"traceutil/trace.go:171","msg":"trace[388335349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:371; }","duration":"952.785166ms","start":"2026-02-05T15:14:58.17231Z","end":"2026-02-05T15:14:59.125095Z","steps":["trace[388335349] 'agreement among raft nodes before linearized reading' (duration: 952.752526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:14:59.125117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:14:58.172296Z","time spent":"952.817076ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T15:15:14.670916Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200095456890893,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T15:15:14.986081Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.039613595s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T15:15:14.98655Z","caller":"traceutil/trace.go:171","msg":"trace[875809405] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"1.040123738s","start":"2026-02-05T15:15:13.946409Z","end":"2026-02-05T15:15:14.986533Z","steps":["trace[875809405] 'process raft request' (duration: 1.040010857s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:14.986655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:13.946394Z","time spent":"1.040191269s","remote":"127.0.0.1:60414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:15:14.987005Z","caller":"traceutil/trace.go:171","msg":"trace[97177999] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:485; }","duration":"816.82014ms","start":"2026-02-05T15:15:14.170175Z","end":"2026-02-05T15:15:14.986995Z","steps":["trace[97177999] 'read index received' (duration: 816.81696ms)","trace[97177999] 'applied index is now lower than readState.Index' (duration: 2.49µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:15:14.987057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.872451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:15:14.98708Z","caller":"traceutil/trace.go:171","msg":"trace[1395400106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"816.930441ms","start":"2026-02-05T15:15:14.170144Z","end":"2026-02-05T15:15:14.987075Z","steps":["trace[1395400106] 'agreement among raft nodes before linearized reading' (duration: 816.884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:14.987097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:14.170132Z","time spent":"816.961131ms","remote":"127.0.0.1:60226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T15:15:15.6504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.914018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T15:15:15.650467Z","caller":"traceutil/trace.go:171","msg":"trace[1378988710] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"661.150887ms","start":"2026-02-05T15:15:14.989305Z","end":"2026-02-05T15:15:15.650455Z","steps":["trace[1378988710] 'read index received' (duration: 224.069118ms)","trace[1378988710] 'applied index is now lower than readState.Index' (duration: 437.080919ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:15:15.650615Z","caller":"traceutil/trace.go:171","msg":"trace[1082417888] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"674.842669ms","start":"2026-02-05T15:15:14.975762Z","end":"2026-02-05T15:15:15.650605Z","steps":["trace[1082417888] 'process raft request' (duration: 237.659039ms)","trace[1082417888] 'compare' (duration: 436.836578ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:15:15.650816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:14.975707Z","time spent":"675.01004ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:15:15.651064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.636013ms","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-02-05T15:15:15.651083Z","caller":"traceutil/trace.go:171","msg":"trace[1542688123] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:472; }","duration":"605.684643ms","start":"2026-02-05T15:15:15.045393Z","end":"2026-02-05T15:15:15.651077Z","steps":["trace[1542688123] 'agreement among raft nodes before linearized reading' (duration: 605.647083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:15.651099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:15.045374Z","time spent":"605.721593ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T15:15:15.65118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.874322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:15:15.651193Z","caller":"traceutil/trace.go:171","msg":"trace[992006906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:472; }","duration":"661.905892ms","start":"2026-02-05T15:15:14.989283Z","end":"2026-02-05T15:15:15.651189Z","steps":["trace[992006906] 'agreement among raft nodes before linearized reading' (duration: 661.887192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:15.651206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:14.989273Z","time spent":"661.930352ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T15:15:15.651278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.831389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T15:15:15.65129Z","caller":"traceutil/trace.go:171","msg":"trace[2085398955] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:472; }","duration":"112.874079ms","start":"2026-02-05T15:15:15.538412Z","end":"2026-02-05T15:15:15.651286Z","steps":["trace[2085398955] 'agreement among raft nodes before linearized reading' (duration: 112.845279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:15.651353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.00938ms","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-02-05T15:15:15.651365Z","caller":"traceutil/trace.go:171","msg":"trace[562516679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:472; }","duration":"148.03427ms","start":"2026-02-05T15:15:15.503328Z","end":"2026-02-05T15:15:15.651362Z","steps":["trace[562516679] 'agreement among raft nodes before linearized reading' (duration: 148.01289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:15.651402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.0793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-05T15:15:15.651414Z","caller":"traceutil/trace.go:171","msg":"trace[1561068903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:472; }","duration":"148.09795ms","start":"2026-02-05T15:15:15.503311Z","end":"2026-02-05T15:15:15.651409Z","steps":["trace[1561068903] 'agreement among raft nodes before linearized reading' (duration: 148.08137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:15.65145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.152511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T15:15:15.651461Z","caller":"traceutil/trace.go:171","msg":"trace[2079235749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:472; }","duration":"148.190891ms","start":"2026-02-05T15:15:15.503266Z","end":"2026-02-05T15:15:15.651457Z","steps":["trace[2079235749] 'agreement among raft nodes before linearized reading' (duration: 148.174691ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:15:21.90212Z","caller":"traceutil/trace.go:171","msg":"trace[1265751233] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"193.788473ms","start":"2026-02-05T15:15:21.708312Z","end":"2026-02-05T15:15:21.902101Z","steps":["trace[1265751233] 'read index received' (duration: 160.476257ms)","trace[1265751233] 'applied index is now lower than readState.Index' (duration: 33.311596ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:15:21.902241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.908904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T15:15:21.902263Z","caller":"traceutil/trace.go:171","msg":"trace[1385682660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"193.961304ms","start":"2026-02-05T15:15:21.708295Z","end":"2026-02-05T15:15:21.902256Z","steps":["trace[1385682660] 'agreement among raft nodes before linearized reading' (duration: 193.875484ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:15:21.902381Z","caller":"traceutil/trace.go:171","msg":"trace[432232391] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"194.88515ms","start":"2026-02-05T15:15:21.707476Z","end":"2026-02-05T15:15:21.902361Z","steps":["trace[432232391] 'process raft request' (duration: 161.352842ms)","trace[432232391] 'compare' (duration: 33.183796ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:15:22.910404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.962324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xfrxp\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-05T15:15:22.910459Z","caller":"traceutil/trace.go:171","msg":"trace[58280441] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xfrxp; range_end:; response_count:1; response_revision:515; }","duration":"733.051094ms","start":"2026-02-05T15:15:22.177397Z","end":"2026-02-05T15:15:22.910448Z","steps":["trace[58280441] 'range keys from in-memory index tree' (duration: 732.856293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:22.91048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:22.177382Z","time spent":"733.093174ms","remote":"127.0.0.1:60426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-xfrxp\" "} {"level":"warn","ts":"2026-02-05T15:15:25.016879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.831077411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:15:25.016947Z","caller":"traceutil/trace.go:171","msg":"trace[1617858748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"1.831175291s","start":"2026-02-05T15:15:23.185755Z","end":"2026-02-05T15:15:25.016931Z","steps":["trace[1617858748] 'range keys from in-memory index tree' (duration: 1.8310324s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:25.01698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:23.185743Z","time spent":"1.831230441s","remote":"127.0.0.1:60226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T15:15:25.017462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323524691s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T15:15:25.017506Z","caller":"traceutil/trace.go:171","msg":"trace[2011901591] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"1.323594291s","start":"2026-02-05T15:15:23.693903Z","end":"2026-02-05T15:15:25.017497Z","steps":["trace[2011901591] 'range keys from in-memory index tree' (duration: 1.32340658s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:25.01752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:23.693885Z","time spent":"1.323631331s","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T15:15:25.017607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.320040311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T15:15:25.01762Z","caller":"traceutil/trace.go:171","msg":"trace[1237592574] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:516; }","duration":"1.320084141s","start":"2026-02-05T15:15:23.697532Z","end":"2026-02-05T15:15:25.017616Z","steps":["trace[1237592574] 'range keys from in-memory index tree' (duration: 1.319962531s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:25.017631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:23.697516Z","time spent":"1.320111911s","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T15:15:25.017751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11192921s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T15:15:25.017763Z","caller":"traceutil/trace.go:171","msg":"trace[37750051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"1.11194232s","start":"2026-02-05T15:15:23.905817Z","end":"2026-02-05T15:15:25.01776Z","steps":["trace[37750051] 'range keys from in-memory index tree' (duration: 1.111874888s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:25.017777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:23.905778Z","time spent":"1.11199552s","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T15:15:25.017909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038605858s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T15:15:25.017927Z","caller":"traceutil/trace.go:171","msg":"trace[787113826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:516; }","duration":"1.038652208s","start":"2026-02-05T15:15:23.979271Z","end":"2026-02-05T15:15:25.017923Z","steps":["trace[787113826] 'range keys from in-memory index tree' (duration: 1.038553998s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:25.017938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:23.979253Z","time spent":"1.038682329s","remote":"127.0.0.1:60500","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-02-05T15:15:25.27138Z","caller":"traceutil/trace.go:171","msg":"trace[565442433] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"248.124689ms","start":"2026-02-05T15:15:25.023225Z","end":"2026-02-05T15:15:25.271349Z","steps":["trace[565442433] 'process raft request' (duration: 247.980378ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:15:25.344778Z","caller":"traceutil/trace.go:171","msg":"trace[151796242] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:533; }","duration":"315.20103ms","start":"2026-02-05T15:15:25.029563Z","end":"2026-02-05T15:15:25.344764Z","steps":["trace[151796242] 'read index received' (duration: 242.272174ms)","trace[151796242] 'applied index is now lower than readState.Index' (duration: 72.928256ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:15:25.344865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.285491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:15:25.344881Z","caller":"traceutil/trace.go:171","msg":"trace[1519000531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"315.339391ms","start":"2026-02-05T15:15:25.029536Z","end":"2026-02-05T15:15:25.344876Z","steps":["trace[1519000531] 'agreement among raft nodes before linearized reading' (duration: 315.284651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:25.344898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:25.029525Z","time spent":"315.369511ms","remote":"127.0.0.1:60226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T15:15:25.345166Z","caller":"traceutil/trace.go:171","msg":"trace[614770118] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"321.459027ms","start":"2026-02-05T15:15:25.023702Z","end":"2026-02-05T15:15:25.345161Z","steps":["trace[614770118] 'process raft request' (duration: 318.299279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:25.345211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:25.023696Z","time spent":"321.483228ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T15:15:25.345293Z","caller":"traceutil/trace.go:171","msg":"trace[611574635] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"321.554188ms","start":"2026-02-05T15:15:25.023734Z","end":"2026-02-05T15:15:25.345289Z","steps":["trace[611574635] 'process raft request' (duration: 320.977705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:25.345318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:25.023731Z","time spent":"321.573598ms","remote":"127.0.0.1:60500","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-02-05T15:15:25.345375Z","caller":"traceutil/trace.go:171","msg":"trace[2137988235] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"320.985994ms","start":"2026-02-05T15:15:25.024384Z","end":"2026-02-05T15:15:25.34537Z","steps":["trace[2137988235] 'process raft request' (duration: 320.34722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:25.345397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:15:25.024378Z","time spent":"321.006444ms","remote":"127.0.0.1:60500","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-02-05T15:15:26.303739Z","caller":"traceutil/trace.go:171","msg":"trace[751090074] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"114.505507ms","start":"2026-02-05T15:15:26.189215Z","end":"2026-02-05T15:15:26.303721Z","steps":["trace[751090074] 'process raft request' (duration: 114.410006ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:15:26.30489Z","caller":"traceutil/trace.go:171","msg":"trace[88034325] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"115.366292ms","start":"2026-02-05T15:15:26.189513Z","end":"2026-02-05T15:15:26.30488Z","steps":["trace[88034325] 'read index received' (duration: 113.871533ms)","trace[88034325] 'applied index is now lower than readState.Index' (duration: 1.493679ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:15:26.305063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.577073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xfrxp\" ","response":"range_response_count:1 size:16818"} {"level":"info","ts":"2026-02-05T15:15:26.305092Z","caller":"traceutil/trace.go:171","msg":"trace[2017533923] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xfrxp; range_end:; response_count:1; response_revision:523; }","duration":"115.633773ms","start":"2026-02-05T15:15:26.189451Z","end":"2026-02-05T15:15:26.305085Z","steps":["trace[2017533923] 'agreement among raft nodes before linearized reading' (duration: 115.465702ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:15:30.597123Z","caller":"traceutil/trace.go:171","msg":"trace[1032305573] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"244.794212ms","start":"2026-02-05T15:15:30.352309Z","end":"2026-02-05T15:15:30.597103Z","steps":["trace[1032305573] 'process raft request' (duration: 178.291858ms)","trace[1032305573] 'compare' (duration: 66.106412ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:15:32.341355Z","caller":"traceutil/trace.go:171","msg":"trace[1283133595] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"108.101751ms","start":"2026-02-05T15:15:32.233235Z","end":"2026-02-05T15:15:32.341337Z","steps":["trace[1283133595] 'process raft request' (duration: 82.823976ms)","trace[1283133595] 'compare' (duration: 25.181475ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:15:32.354756Z","caller":"traceutil/trace.go:171","msg":"trace[281949805] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"121.183735ms","start":"2026-02-05T15:15:32.233556Z","end":"2026-02-05T15:15:32.35474Z","steps":["trace[281949805] 'process raft request' (duration: 120.762933ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:15:32.354929Z","caller":"traceutil/trace.go:171","msg":"trace[668933026] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"121.282666ms","start":"2026-02-05T15:15:32.233638Z","end":"2026-02-05T15:15:32.354921Z","steps":["trace[668933026] 'process raft request' (duration: 120.829694ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:15:32.503636Z","caller":"traceutil/trace.go:171","msg":"trace[1781731846] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"103.876937ms","start":"2026-02-05T15:15:32.399742Z","end":"2026-02-05T15:15:32.503619Z","steps":["trace[1781731846] 'process raft request' (duration: 89.026111ms)","trace[1781731846] 'compare' (duration: 14.779146ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:15:43.709332Z","caller":"traceutil/trace.go:171","msg":"trace[780349307] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:735; }","duration":"198.82315ms","start":"2026-02-05T15:15:43.510489Z","end":"2026-02-05T15:15:43.709312Z","steps":["trace[780349307] 'read index received' (duration: 198.660809ms)","trace[780349307] 'applied index is now lower than readState.Index' (duration: 161.491µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:15:43.709431Z","caller":"traceutil/trace.go:171","msg":"trace[347631629] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"220.713414ms","start":"2026-02-05T15:15:43.488709Z","end":"2026-02-05T15:15:43.709422Z","steps":["trace[347631629] 'process raft request' (duration: 220.457623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:43.709644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.139052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T15:15:43.709678Z","caller":"traceutil/trace.go:171","msg":"trace[327126991] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:715; }","duration":"199.204412ms","start":"2026-02-05T15:15:43.510462Z","end":"2026-02-05T15:15:43.709667Z","steps":["trace[327126991] 'agreement among raft nodes before linearized reading' (duration: 199.077462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:43.709753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.701747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T15:15:43.709786Z","caller":"traceutil/trace.go:171","msg":"trace[1009997061] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:715; }","duration":"169.770537ms","start":"2026-02-05T15:15:43.540007Z","end":"2026-02-05T15:15:43.709777Z","steps":["trace[1009997061] 'agreement among raft nodes before linearized reading' (duration: 169.685887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:15:43.709912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.803438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T15:15:43.709941Z","caller":"traceutil/trace.go:171","msg":"trace[956617797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:715; }","duration":"155.856298ms","start":"2026-02-05T15:15:43.554076Z","end":"2026-02-05T15:15:43.709933Z","steps":["trace[956617797] 'agreement among raft nodes before linearized reading' (duration: 155.781887ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:15:46.677196Z","caller":"traceutil/trace.go:171","msg":"trace[1687839556] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"196.035169ms","start":"2026-02-05T15:15:46.481136Z","end":"2026-02-05T15:15:46.677171Z","steps":["trace[1687839556] 'process raft request' (duration: 195.876229ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:15:46.678653Z","caller":"traceutil/trace.go:171","msg":"trace[1833158237] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"152.817688ms","start":"2026-02-05T15:15:46.525815Z","end":"2026-02-05T15:15:46.678633Z","steps":["trace[1833158237] 'process raft request' (duration: 152.712317ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:15:49.948746Z","caller":"traceutil/trace.go:171","msg":"trace[593989445] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"129.085051ms","start":"2026-02-05T15:15:49.819622Z","end":"2026-02-05T15:15:49.948707Z","steps":["trace[593989445] 'process raft request' (duration: 121.80334ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:16:40.505777Z","caller":"traceutil/trace.go:171","msg":"trace[1378084679] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"148.724945ms","start":"2026-02-05T15:16:40.35702Z","end":"2026-02-05T15:16:40.505745Z","steps":["trace[1378084679] 'process raft request' (duration: 99.157297ms)","trace[1378084679] 'compare' (duration: 49.394026ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:16:49.463421Z","caller":"traceutil/trace.go:171","msg":"trace[436002052] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"281.182858ms","start":"2026-02-05T15:16:49.182217Z","end":"2026-02-05T15:16:49.4634Z","steps":["trace[436002052] 'read index received' (duration: 280.979837ms)","trace[436002052] 'applied index is now lower than readState.Index' (duration: 201.841µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:16:49.463535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.305159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T15:16:49.463563Z","caller":"traceutil/trace.go:171","msg":"trace[723239005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"281.390809ms","start":"2026-02-05T15:16:49.182164Z","end":"2026-02-05T15:16:49.463554Z","steps":["trace[723239005] 'agreement among raft nodes before linearized reading' (duration: 281.324399ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:16:49.463728Z","caller":"traceutil/trace.go:171","msg":"trace[527251602] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"393.738806ms","start":"2026-02-05T15:16:49.069962Z","end":"2026-02-05T15:16:49.463701Z","steps":["trace[527251602] 'process raft request' (duration: 393.310944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:16:49.463861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:16:49.069952Z","time spent":"393.838536ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:16:56.043353Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200095456892053,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T15:16:56.116699Z","caller":"traceutil/trace.go:171","msg":"trace[1229528888] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"573.984157ms","start":"2026-02-05T15:16:55.5427Z","end":"2026-02-05T15:16:56.116684Z","steps":["trace[1229528888] 'read index received' (duration: 573.812766ms)","trace[1229528888] 'applied index is now lower than readState.Index' (duration: 170.661µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T15:16:56.117096Z","caller":"traceutil/trace.go:171","msg":"trace[1442822789] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"626.210638ms","start":"2026-02-05T15:16:55.490876Z","end":"2026-02-05T15:16:56.117087Z","steps":["trace[1442822789] 'process raft request' (duration: 625.700595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:16:56.117337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:16:55.490863Z","time spent":"626.255508ms","remote":"127.0.0.1:60500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T15:16:56.11747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.768291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T15:16:56.117507Z","caller":"traceutil/trace.go:171","msg":"trace[1525792055] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:961; }","duration":"574.826102ms","start":"2026-02-05T15:16:55.542673Z","end":"2026-02-05T15:16:56.117499Z","steps":["trace[1525792055] 'agreement among raft nodes before linearized reading' (duration: 574.740131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T15:16:56.117526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T15:16:55.542655Z","time spent":"574.866502ms","remote":"127.0.0.1:60272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.144\" "} {"level":"warn","ts":"2026-02-05T15:17:14.784196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.04031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/default/\" range_end:\"/registry/configmaps/default0\" limit:500 ","response":"range_response_count:1 size:1738"} {"level":"info","ts":"2026-02-05T15:17:14.784276Z","caller":"traceutil/trace.go:171","msg":"trace[4973073] range","detail":"{range_begin:/registry/configmaps/default/; range_end:/registry/configmaps/default0; response_count:1; response_revision:1020; }","duration":"104.131681ms","start":"2026-02-05T15:17:14.680126Z","end":"2026-02-05T15:17:14.784258Z","steps":["trace[4973073] 'range keys from in-memory index tree' (duration: 103.959769ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:17:16.580916Z","caller":"traceutil/trace.go:171","msg":"trace[1315363199] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"111.59013ms","start":"2026-02-05T15:17:16.469304Z","end":"2026-02-05T15:17:16.580895Z","steps":["trace[1315363199] 'process raft request' (duration: 65.908564ms)","trace[1315363199] 'compare' (duration: 45.240744ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:17:16.582701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.82958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T15:17:16.58275Z","caller":"traceutil/trace.go:171","msg":"trace[1912963918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1024; }","duration":"107.92747ms","start":"2026-02-05T15:17:16.474811Z","end":"2026-02-05T15:17:16.582738Z","steps":["trace[1912963918] 'agreement among raft nodes before linearized reading' (duration: 107.736379ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:17:16.583905Z","caller":"traceutil/trace.go:171","msg":"trace[2109656363] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1064; }","duration":"105.734998ms","start":"2026-02-05T15:17:16.474828Z","end":"2026-02-05T15:17:16.580563Z","steps":["trace[2109656363] 'read index received' (duration: 47.064722ms)","trace[2109656363] 'applied index is now lower than readState.Index' (duration: 58.669376ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T15:17:16.584313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.98386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-02-05T15:17:16.585769Z","caller":"traceutil/trace.go:171","msg":"trace[1239605293] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1024; }","duration":"105.437937ms","start":"2026-02-05T15:17:16.480316Z","end":"2026-02-05T15:17:16.585754Z","steps":["trace[1239605293] 'agreement among raft nodes before linearized reading' (duration: 103.798059ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T15:17:20.671558Z","caller":"traceutil/trace.go:171","msg":"trace[1123753170] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"131.683838ms","start":"2026-02-05T15:17:20.539853Z","end":"2026-02-05T15:17:20.671537Z","steps":["trace[1123753170] 'process raft request' (duration: 131.351547ms)"],"step_count":1}