{"level":"warn","ts":"2026-08-18T23:20:14.21008Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-18T23:20:14.210185Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-18T23:20:14.210268Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-18T23:20:14.210285Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-08-18T23:20:14.21031Z","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-08-18T23:20:14.212393Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-08-18T23:20:14.212528Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-18T23:20:14.218197Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.427721ms"} {"level":"info","ts":"2026-08-18T23:20:14.224699Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-08-18T23:20:14.224783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-08-18T23:20:14.22481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-08-18T23:20:14.224886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-18T23:20:14.224906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-08-18T23:20:14.225003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-08-18T23:20:14.232643Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-18T23:20:14.234718Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-18T23:20:14.235725Z","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-08-18T23:20:14.238567Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-18T23:20:14.240904Z","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-08-18T23:20:14.240989Z","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-08-18T23:20:14.238721Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-18T23:20:14.241009Z","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-08-18T23:20:14.24166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-08-18T23:20:14.241986Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-08-18T23:20:14.250274Z","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-08-18T23:20:14.250428Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-08-18T23:20:14.250446Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-08-18T23:20:14.251365Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-18T23:20:14.251402Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-18T23:20:15.226662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-08-18T23:20:15.226723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-08-18T23:20:15.22674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-08-18T23:20:15.226754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-08-18T23:20:15.226761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-08-18T23:20:15.226896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-08-18T23:20:15.22699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-08-18T23:20:15.227974Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T23:20:15.229474Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-08-18T23:20:15.229508Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-18T23:20:15.230028Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-18T23:20:15.230216Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-18T23:20:15.230265Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-18T23:20:15.230974Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T23:20:15.231099Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T23:20:15.231126Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T23:20:15.232862Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-08-18T23:20:15.233011Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-18T23:20:18.207868Z","caller":"traceutil/trace.go:171","msg":"trace[1902050496] transaction","detail":"{read_only:false; response_revision:144; number_of_response:1; }","duration":"337.578756ms","start":"2026-08-18T23:20:17.870253Z","end":"2026-08-18T23:20:18.207832Z","steps":["trace[1902050496] 'process raft request' (duration: 278.577341ms)","trace[1902050496] 'compare' (duration: 58.843572ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:18.208493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:17.87024Z","time spent":"337.715498ms","remote":"127.0.0.1:34350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-18T23:20:18.442069Z","caller":"traceutil/trace.go:171","msg":"trace[1691371535] linearizableReadLoop","detail":"{readStateIndex:149; appliedIndex:148; }","duration":"231.006828ms","start":"2026-08-18T23:20:18.211032Z","end":"2026-08-18T23:20:18.442039Z","steps":["trace[1691371535] 'read index received' (duration: 229.766547ms)","trace[1691371535] 'applied index is now lower than readState.Index' (duration: 1.239371ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:18.442237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.19029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:20:18.442366Z","caller":"traceutil/trace.go:171","msg":"trace[1356409356] range","detail":"{range_begin:/registry/clusterrolebindings/system:node; range_end:; response_count:0; response_revision:145; }","duration":"231.393193ms","start":"2026-08-18T23:20:18.210959Z","end":"2026-08-18T23:20:18.442352Z","steps":["trace[1356409356] 'agreement among raft nodes before linearized reading' (duration: 231.19515ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:18.442382Z","caller":"traceutil/trace.go:171","msg":"trace[1878475906] transaction","detail":"{read_only:false; response_revision:145; number_of_response:1; }","duration":"405.194786ms","start":"2026-08-18T23:20:18.037167Z","end":"2026-08-18T23:20:18.442361Z","steps":["trace[1878475906] 'process raft request' (duration: 403.709392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:18.442572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:18.037134Z","time spent":"405.344267ms","remote":"127.0.0.1:34190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-18T23:20:55.081602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.779694ms","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-08-18T23:20:55.081724Z","caller":"traceutil/trace.go:171","msg":"trace[24607312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:567; }","duration":"122.955507ms","start":"2026-08-18T23:20:54.958748Z","end":"2026-08-18T23:20:55.081704Z","steps":["trace[24607312] 'range keys from in-memory index tree' (duration: 122.604302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:55.081595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.757029ms","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-08-18T23:20:55.081792Z","caller":"traceutil/trace.go:171","msg":"trace[115118165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:567; }","duration":"160.012462ms","start":"2026-08-18T23:20:54.921756Z","end":"2026-08-18T23:20:55.081768Z","steps":["trace[115118165] 'range keys from in-memory index tree' (duration: 159.555616ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:58.891672Z","caller":"traceutil/trace.go:171","msg":"trace[1721944338] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"134.774573ms","start":"2026-08-18T23:20:58.75683Z","end":"2026-08-18T23:20:58.891605Z","steps":["trace[1721944338] 'read index received' (duration: 125.16291ms)","trace[1721944338] 'applied index is now lower than readState.Index' (duration: 9.610533ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:20:58.891804Z","caller":"traceutil/trace.go:171","msg":"trace[1061982040] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"152.090745ms","start":"2026-08-18T23:20:58.739661Z","end":"2026-08-18T23:20:58.891752Z","steps":["trace[1061982040] 'process raft request' (duration: 145.551141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:58.891867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.010796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-18T23:20:58.892076Z","caller":"traceutil/trace.go:171","msg":"trace[1402488342] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:593; }","duration":"135.234538ms","start":"2026-08-18T23:20:58.756797Z","end":"2026-08-18T23:20:58.892031Z","steps":["trace[1402488342] 'agreement among raft nodes before linearized reading' (duration: 135.003916ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:59.285688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.493677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-18T23:20:59.285938Z","caller":"traceutil/trace.go:171","msg":"trace[270555061] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:621; }","duration":"171.211241ms","start":"2026-08-18T23:20:59.114705Z","end":"2026-08-18T23:20:59.285916Z","steps":["trace[270555061] 'read index received' (duration: 113.059467ms)","trace[270555061] 'applied index is now lower than readState.Index' (duration: 58.150404ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:20:59.286009Z","caller":"traceutil/trace.go:171","msg":"trace[739230906] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"383.556685ms","start":"2026-08-18T23:20:58.902391Z","end":"2026-08-18T23:20:59.285948Z","steps":["trace[739230906] 'process raft request' (duration: 40.361079ms)","trace[739230906] 'compare' (duration: 342.323745ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:59.286219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.479235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-18T23:20:59.28627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:58.90238Z","time spent":"383.748847ms","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:20:59.28631Z","caller":"traceutil/trace.go:171","msg":"trace[2112901501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:596; }","duration":"171.642526ms","start":"2026-08-18T23:20:59.11465Z","end":"2026-08-18T23:20:59.286293Z","steps":["trace[2112901501] 'agreement among raft nodes before linearized reading' (duration: 171.384223ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:59.286315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.657644ms","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-08-18T23:20:59.286358Z","caller":"traceutil/trace.go:171","msg":"trace[163564403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:596; }","duration":"170.715785ms","start":"2026-08-18T23:20:59.115629Z","end":"2026-08-18T23:20:59.286345Z","steps":["trace[163564403] 'agreement among raft nodes before linearized reading' (duration: 170.473092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:59.286387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.741492ms","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-08-18T23:20:59.286458Z","caller":"traceutil/trace.go:171","msg":"trace[473266647] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"332.222305ms","start":"2026-08-18T23:20:58.953761Z","end":"2026-08-18T23:20:59.285983Z","steps":["trace[473266647] 'process raft request' (duration: 332.090164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:59.286644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:58.953735Z","time spent":"332.835263ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-18T23:20:59.28647Z","caller":"traceutil/trace.go:171","msg":"trace[108880863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"162.873074ms","start":"2026-08-18T23:20:59.123577Z","end":"2026-08-18T23:20:59.28645Z","steps":["trace[108880863] 'agreement among raft nodes before linearized reading' (duration: 162.57446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:21:15.69361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.572591ms","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-08-18T23:21:15.693713Z","caller":"traceutil/trace.go:171","msg":"trace[920233186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:756; }","duration":"175.715473ms","start":"2026-08-18T23:21:15.517977Z","end":"2026-08-18T23:21:15.693692Z","steps":["trace[920233186] 'range keys from in-memory index tree' (duration: 175.346449ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:21:15.693777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.83177ms","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-08-18T23:21:15.693869Z","caller":"traceutil/trace.go:171","msg":"trace[1574858550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:756; }","duration":"183.979102ms","start":"2026-08-18T23:21:15.509868Z","end":"2026-08-18T23:21:15.693847Z","steps":["trace[1574858550] 'range keys from in-memory index tree' (duration: 183.597776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:21:15.693808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.855585ms","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-08-18T23:21:15.694178Z","caller":"traceutil/trace.go:171","msg":"trace[2071221855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"176.250349ms","start":"2026-08-18T23:21:15.517906Z","end":"2026-08-18T23:21:15.694156Z","steps":["trace[2071221855] 'range keys from in-memory index tree' (duration: 175.728893ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:21:15.80861Z","caller":"traceutil/trace.go:171","msg":"trace[439274799] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"110.216139ms","start":"2026-08-18T23:21:15.69835Z","end":"2026-08-18T23:21:15.808567Z","steps":["trace[439274799] 'process raft request' (duration: 110.069317ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:21:15.822034Z","caller":"traceutil/trace.go:171","msg":"trace[1998075675] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"123.239685ms","start":"2026-08-18T23:21:15.698756Z","end":"2026-08-18T23:21:15.821996Z","steps":["trace[1998075675] 'process raft request' (duration: 123.070303ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:21:15.82205Z","caller":"traceutil/trace.go:171","msg":"trace[63635979] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"122.146562ms","start":"2026-08-18T23:21:15.699867Z","end":"2026-08-18T23:21:15.822014Z","steps":["trace[63635979] 'process raft request' (duration: 122.043571ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:22:39.87909Z","caller":"traceutil/trace.go:171","msg":"trace[1721987666] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"304.154864ms","start":"2026-08-18T23:22:39.574859Z","end":"2026-08-18T23:22:39.879014Z","steps":["trace[1721987666] 'process raft request' (duration: 231.529349ms)","trace[1721987666] 'compare' (duration: 72.08973ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:22:39.879753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:22:39.574837Z","time spent":"304.382736ms","remote":"127.0.0.1:34262","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-08-18T23:22:40.262761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.983096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18cd09c250019d84\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-08-18T23:22:40.262874Z","caller":"traceutil/trace.go:171","msg":"trace[321209792] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18cd09c250019d84; range_end:; response_count:1; response_revision:1874; }","duration":"140.259648ms","start":"2026-08-18T23:22:40.122582Z","end":"2026-08-18T23:22:40.262841Z","steps":["trace[321209792] 'range keys from in-memory index tree' (duration: 139.688503ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:22:46.504641Z","caller":"traceutil/trace.go:171","msg":"trace[700347498] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"159.450296ms","start":"2026-08-18T23:22:46.345126Z","end":"2026-08-18T23:22:46.504577Z","steps":["trace[700347498] 'process raft request' (duration: 159.387986ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:22:46.504713Z","caller":"traceutil/trace.go:171","msg":"trace[96255417] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"162.045781ms","start":"2026-08-18T23:22:46.342637Z","end":"2026-08-18T23:22:46.504683Z","steps":["trace[96255417] 'process raft request' (duration: 78.98791ms)","trace[96255417] 'compare' (duration: 82.736808ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:23:15.710072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.065893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-18T23:23:15.71049Z","caller":"traceutil/trace.go:171","msg":"trace[1013585630] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"292.856686ms","start":"2026-08-18T23:23:15.417596Z","end":"2026-08-18T23:23:15.710453Z","steps":["trace[1013585630] 'process raft request' (duration: 155.287138ms)","trace[1013585630] 'compare' (duration: 136.938322ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:28:49.973331Z","caller":"traceutil/trace.go:171","msg":"trace[540001351] linearizableReadLoop","detail":"{readStateIndex:4945; appliedIndex:4944; }","duration":"124.498011ms","start":"2026-08-18T23:28:49.848804Z","end":"2026-08-18T23:28:49.973302Z","steps":["trace[540001351] 'read index received' (duration: 49.634513ms)","trace[540001351] 'applied index is now lower than readState.Index' (duration: 74.861788ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:28:49.973423Z","caller":"traceutil/trace.go:171","msg":"trace[265926069] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"131.566848ms","start":"2026-08-18T23:28:49.841793Z","end":"2026-08-18T23:28:49.97336Z","steps":["trace[265926069] 'process raft request' (duration: 56.70845ms)","trace[265926069] 'compare' (duration: 74.610115ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:28:49.973952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.109599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-08-18T23:28:49.974045Z","caller":"traceutil/trace.go:171","msg":"trace[48237489] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4760; }","duration":"125.24274ms","start":"2026-08-18T23:28:49.848782Z","end":"2026-08-18T23:28:49.974025Z","steps":["trace[48237489] 'agreement among raft nodes before linearized reading' (duration: 124.688663ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:28:50.151222Z","caller":"traceutil/trace.go:171","msg":"trace[1544160903] linearizableReadLoop","detail":"{readStateIndex:4949; appliedIndex:4947; }","duration":"146.731054ms","start":"2026-08-18T23:28:50.004459Z","end":"2026-08-18T23:28:50.15119Z","steps":["trace[1544160903] 'read index received' (duration: 141.158368ms)","trace[1544160903] 'applied index is now lower than readState.Index' (duration: 5.571076ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:28:50.151548Z","caller":"traceutil/trace.go:171","msg":"trace[1649250877] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"161.350055ms","start":"2026-08-18T23:28:49.990155Z","end":"2026-08-18T23:28:50.151505Z","steps":["trace[1649250877] 'process raft request' (duration: 160.94183ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:28:50.151602Z","caller":"traceutil/trace.go:171","msg":"trace[816074321] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"171.762438ms","start":"2026-08-18T23:28:49.979795Z","end":"2026-08-18T23:28:50.151557Z","steps":["trace[816074321] 'process raft request' (duration: 165.824887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:28:50.151663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.504669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-08-18T23:28:50.151711Z","caller":"traceutil/trace.go:171","msg":"trace[1401982146] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4763; }","duration":"151.57611ms","start":"2026-08-18T23:28:50.00012Z","end":"2026-08-18T23:28:50.151696Z","steps":["trace[1401982146] 'agreement among raft nodes before linearized reading' (duration: 151.394257ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:28:50.15208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.363121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-18T23:28:50.152167Z","caller":"traceutil/trace.go:171","msg":"trace[835379567] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4763; }","duration":"160.456283ms","start":"2026-08-18T23:28:49.991686Z","end":"2026-08-18T23:28:50.152142Z","steps":["trace[835379567] 'agreement among raft nodes before linearized reading' (duration: 160.22635ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:28:50.175345Z","caller":"traceutil/trace.go:171","msg":"trace[1749302282] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"168.819796ms","start":"2026-08-18T23:28:50.006494Z","end":"2026-08-18T23:28:50.175314Z","steps":["trace[1749302282] 'process raft request' (duration: 168.634634ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:28:50.175664Z","caller":"traceutil/trace.go:171","msg":"trace[333984331] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"162.556901ms","start":"2026-08-18T23:28:50.013085Z","end":"2026-08-18T23:28:50.175642Z","steps":["trace[333984331] 'process raft request' (duration: 162.176186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:28:50.175817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.420423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-6c8c6dd6d6-hw2xt.18cd0a18d2b9ca3b\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-08-18T23:28:50.176562Z","caller":"traceutil/trace.go:171","msg":"trace[826426926] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-6c8c6dd6d6-hw2xt.18cd0a18d2b9ca3b; range_end:; response_count:1; response_revision:4765; }","duration":"169.186193ms","start":"2026-08-18T23:28:50.007342Z","end":"2026-08-18T23:28:50.176528Z","steps":["trace[826426926] 'agreement among raft nodes before linearized reading' (duration: 168.29695ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:28:50.635059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.327038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-18T23:28:50.635178Z","caller":"traceutil/trace.go:171","msg":"trace[2046201315] linearizableReadLoop","detail":"{readStateIndex:4965; appliedIndex:4964; }","duration":"112.642696ms","start":"2026-08-18T23:28:50.522491Z","end":"2026-08-18T23:28:50.635134Z","steps":["trace[2046201315] 'read index received' (duration: 88.691µs)","trace[2046201315] 'applied index is now lower than readState.Index' (duration: 112.552795ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:28:50.635332Z","caller":"traceutil/trace.go:171","msg":"trace[194648432] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"251.33339ms","start":"2026-08-18T23:28:50.383989Z","end":"2026-08-18T23:28:50.635322Z","steps":["trace[194648432] 'process raft request' (duration: 29.661177ms)","trace[194648432] 'compare' (duration: 221.135545ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:28:50.635697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.208964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-18T23:28:50.635726Z","caller":"traceutil/trace.go:171","msg":"trace[1629935582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4779; }","duration":"113.248315ms","start":"2026-08-18T23:28:50.522468Z","end":"2026-08-18T23:28:50.635717Z","steps":["trace[1629935582] 'agreement among raft nodes before linearized reading' (duration: 113.129183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:28:50.635793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.119043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-18T23:28:50.636577Z","caller":"traceutil/trace.go:171","msg":"trace[1819415746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4779; }","duration":"113.977085ms","start":"2026-08-18T23:28:50.522584Z","end":"2026-08-18T23:28:50.636561Z","steps":["trace[1819415746] 'agreement among raft nodes before linearized reading' (duration: 112.828489ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:28:53.187779Z","caller":"traceutil/trace.go:171","msg":"trace[364041428] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"206.926571ms","start":"2026-08-18T23:28:52.980802Z","end":"2026-08-18T23:28:53.187728Z","steps":["trace[364041428] 'process raft request' (duration: 135.945179ms)","trace[364041428] 'compare' (duration: 70.502916ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:29:43.471491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.738412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:29:43.471576Z","caller":"traceutil/trace.go:171","msg":"trace[1465908226] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5296; }","duration":"101.892333ms","start":"2026-08-18T23:29:43.369666Z","end":"2026-08-18T23:29:43.471558Z","steps":["trace[1465908226] 'agreement among raft nodes before linearized reading' (duration: 101.743761ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:30:15.260184Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2866} {"level":"info","ts":"2026-08-18T23:30:15.358087Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2866,"took":"95.992669ms","hash":1935205153,"current-db-size-bytes":53624832,"current-db-size":"54 MB","current-db-size-in-use-bytes":29224960,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-18T23:30:15.358177Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1935205153,"revision":2866,"compact-revision":-1} {"level":"info","ts":"2026-08-18T23:35:15.264419Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5540} {"level":"info","ts":"2026-08-18T23:35:15.340493Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5540,"took":"74.451776ms","hash":3368360262,"current-db-size-bytes":53624832,"current-db-size":"54 MB","current-db-size-in-use-bytes":18460672,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-18T23:35:15.340626Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3368360262,"revision":5540,"compact-revision":2866}