{"level":"warn","ts":"2026-09-25T15:06:11.346699Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-25T15:06:11.346806Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.99: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.99:2380","--initial-cluster=instance=https://199.204.45.99:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.99:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.99: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-09-25T15:06:11.346902Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-25T15:06:11.346917Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.99:2380"]} {"level":"info","ts":"2026-09-25T15:06:11.346977Z","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-09-25T15:06:11.347633Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"]} {"level":"info","ts":"2026-09-25T15:06:11.347711Z","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.99:2380"],"listen-peer-urls":["https://199.204.45.99:2380"],"advertise-client-urls":["https://199.204.45.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.99: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-09-25T15:06:11.358066Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.078135ms"} {"level":"info","ts":"2026-09-25T15:06:11.389037Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"21032583b6cd9182","cluster-id":"a96cbe7391f279b7"} {"level":"info","ts":"2026-09-25T15:06:11.389121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=()"} {"level":"info","ts":"2026-09-25T15:06:11.38914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became follower at term 0"} {"level":"info","ts":"2026-09-25T15:06:11.389147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 21032583b6cd9182 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-25T15:06:11.389154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became follower at term 1"} {"level":"info","ts":"2026-09-25T15:06:11.389183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=(2378786275819622786)"} {"level":"warn","ts":"2026-09-25T15:06:11.398286Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-25T15:06:11.399926Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-25T15:06:11.401746Z","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-09-25T15:06:11.403718Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"21032583b6cd9182","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-25T15:06:11.404138Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"21032583b6cd9182","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-25T15:06:11.404268Z","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-09-25T15:06:11.404461Z","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-09-25T15:06:11.407299Z","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-09-25T15:06:11.408708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=(2378786275819622786)"} {"level":"info","ts":"2026-09-25T15:06:11.408855Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a96cbe7391f279b7","local-member-id":"21032583b6cd9182","added-peer-id":"21032583b6cd9182","added-peer-peer-urls":["https://199.204.45.99:2380"]} {"level":"info","ts":"2026-09-25T15:06:11.414551Z","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-09-25T15:06:11.414657Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.99:2380"} {"level":"info","ts":"2026-09-25T15:06:11.414709Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.99:2380"} {"level":"info","ts":"2026-09-25T15:06:11.414735Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"21032583b6cd9182","initial-advertise-peer-urls":["https://199.204.45.99:2380"],"listen-peer-urls":["https://199.204.45.99:2380"],"advertise-client-urls":["https://199.204.45.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-25T15:06:11.414768Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-25T15:06:11.690027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 is starting a new election at term 1"} {"level":"info","ts":"2026-09-25T15:06:11.690179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-25T15:06:11.69026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 received MsgPreVoteResp from 21032583b6cd9182 at term 1"} {"level":"info","ts":"2026-09-25T15:06:11.690359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became candidate at term 2"} {"level":"info","ts":"2026-09-25T15:06:11.69041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 received MsgVoteResp from 21032583b6cd9182 at term 2"} {"level":"info","ts":"2026-09-25T15:06:11.690475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became leader at term 2"} {"level":"info","ts":"2026-09-25T15:06:11.690544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 21032583b6cd9182 elected leader 21032583b6cd9182 at term 2"} {"level":"info","ts":"2026-09-25T15:06:11.694082Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-25T15:06:11.697029Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-25T15:06:11.697169Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-25T15:06:11.697567Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a96cbe7391f279b7","local-member-id":"21032583b6cd9182","cluster-version":"3.5"} {"level":"info","ts":"2026-09-25T15:06:11.697687Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-25T15:06:11.697741Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-25T15:06:11.697792Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-25T15:06:11.697829Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-25T15:06:11.697044Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"21032583b6cd9182","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.99:2379]}","request-path":"/0/members/21032583b6cd9182/attributes","cluster-id":"a96cbe7391f279b7","publish-timeout":"7s"} {"level":"info","ts":"2026-09-25T15:06:11.700746Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.99:2379"} {"level":"info","ts":"2026-09-25T15:06:11.701499Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-25T15:06:27.820703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485119736780701773,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-25T15:06:28.320909Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485119736780701773,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-25T15:06:28.418495Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.120450176s","expected-duration":"1s"} {"level":"info","ts":"2026-09-25T15:06:28.419186Z","caller":"traceutil/trace.go:171","msg":"trace[1411846983] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"1.099024535s","start":"2026-09-25T15:06:27.320134Z","end":"2026-09-25T15:06:28.419158Z","steps":["trace[1411846983] 'read index received' (duration: 1.09855708s)","trace[1411846983] 'applied index is now lower than readState.Index' (duration: 464.175µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:06:28.4193Z","caller":"traceutil/trace.go:171","msg":"trace[564846531] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"1.121297013s","start":"2026-09-25T15:06:27.297961Z","end":"2026-09-25T15:06:28.419258Z","steps":["trace[564846531] 'process raft request' (duration: 1.121046375s)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:06:28.419662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099469419s","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-09-25T15:06:28.419821Z","caller":"traceutil/trace.go:171","msg":"trace[1254301453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:369; }","duration":"1.099674786s","start":"2026-09-25T15:06:27.320109Z","end":"2026-09-25T15:06:28.419784Z","steps":["trace[1254301453] 'agreement among raft nodes before linearized reading' (duration: 1.099328914s)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:06:28.419903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:06:27.3201Z","time spent":"1.09978592s","remote":"127.0.0.1:52844","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-09-25T15:06:28.419999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.666721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-25T15:06:28.420159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:06:27.297931Z","time spent":"1.121462329s","remote":"127.0.0.1:52776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T15:06:28.419554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.288066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-25T15:06:28.420524Z","caller":"traceutil/trace.go:171","msg":"trace[39939244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:369; }","duration":"927.274167ms","start":"2026-09-25T15:06:27.493178Z","end":"2026-09-25T15:06:28.420453Z","steps":["trace[39939244] 'agreement among raft nodes before linearized reading' (duration: 926.294775ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:06:28.420732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:06:27.493158Z","time spent":"927.492395ms","remote":"127.0.0.1:52844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-25T15:06:28.421713Z","caller":"traceutil/trace.go:171","msg":"trace[387080062] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:369; }","duration":"490.857207ms","start":"2026-09-25T15:06:27.929264Z","end":"2026-09-25T15:06:28.420121Z","steps":["trace[387080062] 'agreement among raft nodes before linearized reading' (duration: 490.536497ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:06:28.421777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:06:27.929237Z","time spent":"492.524ms","remote":"127.0.0.1:52844","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-09-25T15:06:28.731639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.808943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-25T15:06:28.731872Z","caller":"traceutil/trace.go:171","msg":"trace[798482146] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"305.220331ms","start":"2026-09-25T15:06:28.426635Z","end":"2026-09-25T15:06:28.731855Z","steps":["trace[798482146] 'process raft request' (duration: 305.18273ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:06:28.731969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:06:28.426625Z","time spent":"305.288173ms","remote":"127.0.0.1:52844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T15:06:28.732107Z","caller":"traceutil/trace.go:171","msg":"trace[1010178159] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"306.228233ms","start":"2026-09-25T15:06:28.425867Z","end":"2026-09-25T15:06:28.732095Z","steps":["trace[1010178159] 'process raft request' (duration: 105.703527ms)","trace[1010178159] 'compare' (duration: 199.541265ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T15:06:28.732153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:06:28.425834Z","time spent":"306.291855ms","remote":"127.0.0.1:52844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T15:06:28.732246Z","caller":"traceutil/trace.go:171","msg":"trace[1786076357] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"305.770809ms","start":"2026-09-25T15:06:28.426449Z","end":"2026-09-25T15:06:28.73222Z","steps":["trace[1786076357] 'process raft request' (duration: 305.292804ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:06:28.732269Z","caller":"traceutil/trace.go:171","msg":"trace[785997346] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"306.325297ms","start":"2026-09-25T15:06:28.425908Z","end":"2026-09-25T15:06:28.732233Z","steps":["trace[785997346] 'read index received' (duration: 111.134µs)","trace[785997346] 'applied index is now lower than readState.Index' (duration: 306.211482ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T15:06:28.732384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:06:28.426426Z","time spent":"305.881602ms","remote":"127.0.0.1:52844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T15:06:28.732469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.530643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-09-25T15:06:28.732547Z","caller":"traceutil/trace.go:171","msg":"trace[310289249] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:372; }","duration":"306.672487ms","start":"2026-09-25T15:06:28.425859Z","end":"2026-09-25T15:06:28.732531Z","steps":["trace[310289249] 'agreement among raft nodes before linearized reading' (duration: 306.514362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:06:28.732547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.914951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-25T15:06:28.732585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:06:28.425837Z","time spent":"306.73879ms","remote":"127.0.0.1:52776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7317,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-09-25T15:06:28.732622Z","caller":"traceutil/trace.go:171","msg":"trace[878107884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:372; }","duration":"278.073565ms","start":"2026-09-25T15:06:28.454529Z","end":"2026-09-25T15:06:28.732603Z","steps":["trace[878107884] 'agreement among raft nodes before linearized reading' (duration: 277.9018ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:06:28.732646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.594044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-25T15:06:28.732762Z","caller":"traceutil/trace.go:171","msg":"trace[431198669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:372; }","duration":"109.79864ms","start":"2026-09-25T15:06:28.622934Z","end":"2026-09-25T15:06:28.732733Z","steps":["trace[431198669] 'agreement among raft nodes before linearized reading' (duration: 109.574132ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:06:58.491453Z","caller":"traceutil/trace.go:171","msg":"trace[325224636] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"104.350008ms","start":"2026-09-25T15:06:58.387087Z","end":"2026-09-25T15:06:58.491436Z","steps":["trace[325224636] 'process raft request' (duration: 104.149922ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:06:58.491651Z","caller":"traceutil/trace.go:171","msg":"trace[862686405] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"105.794513ms","start":"2026-09-25T15:06:58.38585Z","end":"2026-09-25T15:06:58.491644Z","steps":["trace[862686405] 'process raft request' (duration: 105.243606ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:06:58.49175Z","caller":"traceutil/trace.go:171","msg":"trace[813434368] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"100.0126ms","start":"2026-09-25T15:06:58.391732Z","end":"2026-09-25T15:06:58.491745Z","steps":["trace[813434368] 'process raft request' (duration: 99.628448ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:06:58.491854Z","caller":"traceutil/trace.go:171","msg":"trace[1977756529] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"100.755424ms","start":"2026-09-25T15:06:58.391092Z","end":"2026-09-25T15:06:58.491847Z","steps":["trace[1977756529] 'process raft request' (duration: 100.180216ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:06:58.800012Z","caller":"traceutil/trace.go:171","msg":"trace[1581038738] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"112.128564ms","start":"2026-09-25T15:06:58.687805Z","end":"2026-09-25T15:06:58.799933Z","steps":["trace[1581038738] 'process raft request' (duration: 111.821915ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:06:58.804752Z","caller":"traceutil/trace.go:171","msg":"trace[1364536396] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"110.863055ms","start":"2026-09-25T15:06:58.693857Z","end":"2026-09-25T15:06:58.80472Z","steps":["trace[1364536396] 'process raft request' (duration: 110.657668ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:07:05.155223Z","caller":"traceutil/trace.go:171","msg":"trace[1349607914] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"159.034576ms","start":"2026-09-25T15:07:04.996153Z","end":"2026-09-25T15:07:05.155188Z","steps":["trace[1349607914] 'read index received' (duration: 158.773228ms)","trace[1349607914] 'applied index is now lower than readState.Index' (duration: 260.058µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:07:05.155358Z","caller":"traceutil/trace.go:171","msg":"trace[1614719820] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"182.677118ms","start":"2026-09-25T15:07:04.972613Z","end":"2026-09-25T15:07:05.15529Z","steps":["trace[1614719820] 'process raft request' (duration: 182.399819ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:07:05.155515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.096487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.99\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-25T15:07:05.155571Z","caller":"traceutil/trace.go:171","msg":"trace[897101603] range","detail":"{range_begin:/registry/masterleases/199.204.45.99; range_end:; response_count:1; response_revision:615; }","duration":"147.273343ms","start":"2026-09-25T15:07:05.008282Z","end":"2026-09-25T15:07:05.155555Z","steps":["trace[897101603] 'agreement among raft nodes before linearized reading' (duration: 147.112888ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:07:05.15569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.529541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-25T15:07:05.155868Z","caller":"traceutil/trace.go:171","msg":"trace[1295246258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:615; }","duration":"159.718418ms","start":"2026-09-25T15:07:04.996084Z","end":"2026-09-25T15:07:05.155802Z","steps":["trace[1295246258] 'agreement among raft nodes before linearized reading' (duration: 159.314525ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:07:05.898647Z","caller":"traceutil/trace.go:171","msg":"trace[1517856282] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"443.499079ms","start":"2026-09-25T15:07:05.455123Z","end":"2026-09-25T15:07:05.898622Z","steps":["trace[1517856282] 'read index received' (duration: 442.678173ms)","trace[1517856282] 'applied index is now lower than readState.Index' (duration: 819.866µs)"],"step_count":2} {"level":"warn","ts":"2026-09-25T15:07:05.898864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.728477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T15:07:05.898848Z","caller":"traceutil/trace.go:171","msg":"trace[698683478] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"558.538026ms","start":"2026-09-25T15:07:05.340273Z","end":"2026-09-25T15:07:05.898811Z","steps":["trace[698683478] 'process raft request' (duration: 557.751711ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:07:05.898937Z","caller":"traceutil/trace.go:171","msg":"trace[1746991497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"443.814609ms","start":"2026-09-25T15:07:05.455071Z","end":"2026-09-25T15:07:05.898885Z","steps":["trace[1746991497] 'agreement among raft nodes before linearized reading' (duration: 443.708236ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:07:05.899018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:07:05.455048Z","time spent":"443.942414ms","remote":"127.0.0.1:52600","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-09-25T15:07:05.89906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:07:05.340232Z","time spent":"558.703152ms","remote":"127.0.0.1:52700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T15:07:35.627502Z","caller":"traceutil/trace.go:171","msg":"trace[2009015437] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"104.920825ms","start":"2026-09-25T15:07:35.522547Z","end":"2026-09-25T15:07:35.627468Z","steps":["trace[2009015437] 'process raft request' (duration: 61.380501ms)","trace[2009015437] 'compare' (duration: 43.245544ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T15:08:05.86376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.692939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-25T15:08:05.863986Z","caller":"traceutil/trace.go:171","msg":"trace[150567674] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"403.962023ms","start":"2026-09-25T15:08:05.460005Z","end":"2026-09-25T15:08:05.863967Z","steps":["trace[150567674] 'process raft request' (duration: 403.881741ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:08:05.863986Z","caller":"traceutil/trace.go:171","msg":"trace[1320122871] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"562.599738ms","start":"2026-09-25T15:08:05.301321Z","end":"2026-09-25T15:08:05.863921Z","steps":["trace[1320122871] 'process raft request' (duration: 322.664692ms)","trace[1320122871] 'compare' (duration: 239.524422ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T15:08:05.864054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:08:05.459934Z","time spent":"404.086977ms","remote":"127.0.0.1:52844","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-09-25T15:08:05.864161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:08:05.301307Z","time spent":"562.767774ms","remote":"127.0.0.1:52844","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-09-25T15:08:05.864284Z","caller":"traceutil/trace.go:171","msg":"trace[1152948448] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"411.867085ms","start":"2026-09-25T15:08:05.452407Z","end":"2026-09-25T15:08:05.864274Z","steps":["trace[1152948448] 'read index received' (duration: 171.614159ms)","trace[1152948448] 'applied index is now lower than readState.Index' (duration: 240.251646ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T15:08:05.864479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.593694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-25T15:08:05.864503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.103782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T15:08:05.864524Z","caller":"traceutil/trace.go:171","msg":"trace[1293943552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1081; }","duration":"244.692538ms","start":"2026-09-25T15:08:05.61982Z","end":"2026-09-25T15:08:05.864513Z","steps":["trace[1293943552] 'agreement among raft nodes before linearized reading' (duration: 244.561334ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:08:05.864533Z","caller":"traceutil/trace.go:171","msg":"trace[2052393052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1081; }","duration":"412.185575ms","start":"2026-09-25T15:08:05.452338Z","end":"2026-09-25T15:08:05.864524Z","steps":["trace[2052393052] 'agreement among raft nodes before linearized reading' (duration: 412.133143ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:08:05.864587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T15:08:05.452312Z","time spent":"412.268288ms","remote":"127.0.0.1:52584","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-09-25T15:08:05.864998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.430258ms","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-09-25T15:08:05.865138Z","caller":"traceutil/trace.go:171","msg":"trace[171457208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1081; }","duration":"238.571352ms","start":"2026-09-25T15:08:05.626551Z","end":"2026-09-25T15:08:05.865122Z","steps":["trace[171457208] 'agreement among raft nodes before linearized reading' (duration: 238.281543ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T15:08:28.161783Z","caller":"traceutil/trace.go:171","msg":"trace[1967308380] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"114.803398ms","start":"2026-09-25T15:08:28.046933Z","end":"2026-09-25T15:08:28.161736Z","steps":["trace[1967308380] 'process raft request' (duration: 114.619162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:08:28.205679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.009716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-25T15:08:28.205778Z","caller":"traceutil/trace.go:171","msg":"trace[626868463] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1189; }","duration":"158.174441ms","start":"2026-09-25T15:08:28.047581Z","end":"2026-09-25T15:08:28.205756Z","steps":["trace[626868463] 'agreement among raft nodes before linearized reading' (duration: 114.121707ms)","trace[626868463] 'range keys from in-memory index tree' (duration: 43.784415ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:08:28.672553Z","caller":"traceutil/trace.go:171","msg":"trace[446723417] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"139.64322ms","start":"2026-09-25T15:08:28.532877Z","end":"2026-09-25T15:08:28.67252Z","steps":["trace[446723417] 'process raft request' (duration: 105.251963ms)","trace[446723417] 'compare' (duration: 34.20503ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:08:37.673837Z","caller":"traceutil/trace.go:171","msg":"trace[234617844] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1299; }","duration":"129.401315ms","start":"2026-09-25T15:08:37.544411Z","end":"2026-09-25T15:08:37.673812Z","steps":["trace[234617844] 'read index received' (duration: 129.090275ms)","trace[234617844] 'applied index is now lower than readState.Index' (duration: 309.95µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T15:08:37.673989Z","caller":"traceutil/trace.go:171","msg":"trace[441250955] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"210.198819ms","start":"2026-09-25T15:08:37.463746Z","end":"2026-09-25T15:08:37.673945Z","steps":["trace[441250955] 'process raft request' (duration: 209.869289ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T15:08:37.674024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.5812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-25T15:08:37.67406Z","caller":"traceutil/trace.go:171","msg":"trace[533621892] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1254; }","duration":"129.698514ms","start":"2026-09-25T15:08:37.54435Z","end":"2026-09-25T15:08:37.674049Z","steps":["trace[533621892] 'agreement among raft nodes before linearized reading' (duration: 129.56415ms)"],"step_count":1}