{"level":"warn","ts":"2026-05-11T00:53:02.7404Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T00:53:02.740523Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229: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.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-05-11T00:53:02.740751Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T00:53:02.740778Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-05-11T00:53:02.740871Z","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-05-11T00:53:02.74185Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-05-11T00:53:02.742037Z","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.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-05-11T00:53:02.746123Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.739045ms"} {"level":"info","ts":"2026-05-11T00:53:02.751453Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-05-11T00:53:02.751567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T00:53:02.751587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-05-11T00:53:02.751598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T00:53:02.751608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-05-11T00:53:02.751639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-05-11T00:53:02.75877Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T00:53:02.759906Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T00:53:02.760872Z","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-05-11T00:53:02.762956Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T00:53:02.763153Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T00:53:02.763212Z","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-05-11T00:53:02.763413Z","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-05-11T00:53:02.763431Z","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-05-11T00:53:02.768144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-05-11T00:53:02.768338Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-05-11T00:53:02.772531Z","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-05-11T00:53:02.772646Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-05-11T00:53:02.772675Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-05-11T00:53:02.772851Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T00:53:02.772897Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T00:53:02.852033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T00:53:02.852136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T00:53:02.852206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-05-11T00:53:02.852266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-05-11T00:53:02.852288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-05-11T00:53:02.852347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-05-11T00:53:02.852371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-05-11T00:53:02.853486Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T00:53:02.858338Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:53:02.858474Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:53:02.858746Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:53:02.85935Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:53:02.859433Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:53:02.859479Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:53:02.861369Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T00:53:02.863916Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-05-11T00:53:02.867014Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T00:53:02.867032Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-05-11T00:53:06.56971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.625057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:53:06.56991Z","caller":"traceutil/trace.go:171","msg":"trace[466414560] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"140.148021ms","start":"2026-05-11T00:53:06.429743Z","end":"2026-05-11T00:53:06.569891Z","steps":["trace[466414560] 'process raft request' (duration: 140.092929ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:53:06.570061Z","caller":"traceutil/trace.go:171","msg":"trace[1419688710] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"400.090748ms","start":"2026-05-11T00:53:06.16992Z","end":"2026-05-11T00:53:06.570011Z","steps":["trace[1419688710] 'process raft request' (duration: 243.627333ms)","trace[1419688710] 'compare' (duration: 155.46417ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:53:06.570233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:53:06.169906Z","time spent":"400.245954ms","remote":"127.0.0.1:53818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":694,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:53:06.734466Z","caller":"traceutil/trace.go:171","msg":"trace[777994844] linearizableReadLoop","detail":"{readStateIndex:234; appliedIndex:233; }","duration":"152.900742ms","start":"2026-05-11T00:53:06.581545Z","end":"2026-05-11T00:53:06.734446Z","steps":["trace[777994844] 'read index received' (duration: 149.489857ms)","trace[777994844] 'applied index is now lower than readState.Index' (duration: 3.409785ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:53:06.734531Z","caller":"traceutil/trace.go:171","msg":"trace[1545751958] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"155.807235ms","start":"2026-05-11T00:53:06.57868Z","end":"2026-05-11T00:53:06.734488Z","steps":["trace[1545751958] 'process raft request' (duration: 152.250223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:53:06.734779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.090011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:53:06.734881Z","caller":"traceutil/trace.go:171","msg":"trace[91720011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:229; }","duration":"153.367264ms","start":"2026-05-11T00:53:06.581492Z","end":"2026-05-11T00:53:06.734859Z","steps":["trace[91720011] 'agreement among raft nodes before linearized reading' (duration: 153.040209ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:53:31.928988Z","caller":"traceutil/trace.go:171","msg":"trace[1132437280] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"179.438887ms","start":"2026-05-11T00:53:31.74951Z","end":"2026-05-11T00:53:31.928949Z","steps":["trace[1132437280] 'process raft request' (duration: 166.329288ms)","trace[1132437280] 'compare' (duration: 12.588017ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:53:31.929071Z","caller":"traceutil/trace.go:171","msg":"trace[322709549] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"143.416803ms","start":"2026-05-11T00:53:31.785633Z","end":"2026-05-11T00:53:31.92905Z","steps":["trace[322709549] 'process raft request' (duration: 143.175448ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:53:34.764658Z","caller":"traceutil/trace.go:171","msg":"trace[1034484310] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"193.486659ms","start":"2026-05-11T00:53:34.571143Z","end":"2026-05-11T00:53:34.76463Z","steps":["trace[1034484310] 'process raft request' (duration: 193.037771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:53:37.674532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.845126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:53:37.674712Z","caller":"traceutil/trace.go:171","msg":"trace[819269223] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"120.329057ms","start":"2026-05-11T00:53:37.554351Z","end":"2026-05-11T00:53:37.67468Z","steps":["trace[819269223] 'compare' (duration: 114.716518ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:53:38.483717Z","caller":"traceutil/trace.go:171","msg":"trace[1854038213] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"101.882041ms","start":"2026-05-11T00:53:38.381808Z","end":"2026-05-11T00:53:38.483691Z","steps":["trace[1854038213] 'read index received' (duration: 99.489986ms)","trace[1854038213] 'applied index is now lower than readState.Index' (duration: 2.390874ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:53:38.484253Z","caller":"traceutil/trace.go:171","msg":"trace[2028893590] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"349.475144ms","start":"2026-05-11T00:53:38.13437Z","end":"2026-05-11T00:53:38.483845Z","steps":["trace[2028893590] 'process raft request' (duration: 347.634156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:53:38.487825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.007876ms","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-05-11T00:53:38.487913Z","caller":"traceutil/trace.go:171","msg":"trace[1767413575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"106.140754ms","start":"2026-05-11T00:53:38.381745Z","end":"2026-05-11T00:53:38.487886Z","steps":["trace[1767413575] 'agreement among raft nodes before linearized reading' (duration: 105.994715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:53:38.487185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:53:38.134343Z","time spent":"349.964276ms","remote":"127.0.0.1:53592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:54:25.303069Z","caller":"traceutil/trace.go:171","msg":"trace[242104289] linearizableReadLoop","detail":"{readStateIndex:1232; appliedIndex:1231; }","duration":"101.458255ms","start":"2026-05-11T00:54:25.201583Z","end":"2026-05-11T00:54:25.303042Z","steps":["trace[242104289] 'read index received' (duration: 101.292601ms)","trace[242104289] 'applied index is now lower than readState.Index' (duration: 164.714µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:54:25.303279Z","caller":"traceutil/trace.go:171","msg":"trace[2044708921] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"195.340097ms","start":"2026-05-11T00:54:25.107792Z","end":"2026-05-11T00:54:25.303132Z","steps":["trace[2044708921] 'process raft request' (duration: 195.085447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:25.303323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.715365ms","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-05-11T00:54:25.303679Z","caller":"traceutil/trace.go:171","msg":"trace[1412357126] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1196; }","duration":"102.100432ms","start":"2026-05-11T00:54:25.20155Z","end":"2026-05-11T00:54:25.30365Z","steps":["trace[1412357126] 'agreement among raft nodes before linearized reading' (duration: 101.603509ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:48.099108Z","caller":"traceutil/trace.go:171","msg":"trace[20906974] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"109.999761ms","start":"2026-05-11T00:54:47.989072Z","end":"2026-05-11T00:54:48.099072Z","steps":["trace[20906974] 'process raft request' (duration: 109.785772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:55:11.16419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.862559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:55:11.164501Z","caller":"traceutil/trace.go:171","msg":"trace[1026327391] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1894; }","duration":"235.165214ms","start":"2026-05-11T00:55:10.929318Z","end":"2026-05-11T00:55:11.164483Z","steps":["trace[1026327391] 'read index received' (duration: 28.666277ms)","trace[1026327391] 'applied index is now lower than readState.Index' (duration: 206.496927ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:55:11.164547Z","caller":"traceutil/trace.go:171","msg":"trace[1535718407] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"317.961849ms","start":"2026-05-11T00:55:10.846553Z","end":"2026-05-11T00:55:11.164514Z","steps":["trace[1535718407] 'process raft request' (duration: 111.487042ms)","trace[1535718407] 'compare' (duration: 205.619685ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:55:11.164678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.351716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-11T00:55:11.164725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:55:10.846532Z","time spent":"318.095272ms","remote":"127.0.0.1:53764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:55:11.164739Z","caller":"traceutil/trace.go:171","msg":"trace[1452346158] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1840; }","duration":"235.442517ms","start":"2026-05-11T00:55:10.929283Z","end":"2026-05-11T00:55:11.164725Z","steps":["trace[1452346158] 'agreement among raft nodes before linearized reading' (duration: 235.272825ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:55:11.191259Z","caller":"traceutil/trace.go:171","msg":"trace[214649659] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"165.532677ms","start":"2026-05-11T00:55:11.025704Z","end":"2026-05-11T00:55:11.191237Z","steps":["trace[214649659] 'process raft request' (duration: 165.362795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:55:11.191442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.144572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c7d4244d-9877-485d-a9e2-bdb6d4e76c98\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-11T00:55:11.191549Z","caller":"traceutil/trace.go:171","msg":"trace[320456122] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c7d4244d-9877-485d-a9e2-bdb6d4e76c98; range_end:; response_count:1; response_revision:1841; }","duration":"158.311175ms","start":"2026-05-11T00:55:11.03322Z","end":"2026-05-11T00:55:11.191531Z","steps":["trace[320456122] 'agreement among raft nodes before linearized reading' (duration: 157.98899ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:55:11.321195Z","caller":"traceutil/trace.go:171","msg":"trace[1590323088] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"121.835605ms","start":"2026-05-11T00:55:11.199338Z","end":"2026-05-11T00:55:11.321174Z","steps":["trace[1590323088] 'process raft request' (duration: 85.567208ms)","trace[1590323088] 'compare' (duration: 35.820861ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:55:11.470933Z","caller":"traceutil/trace.go:171","msg":"trace[717081512] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"145.145127ms","start":"2026-05-11T00:55:11.325762Z","end":"2026-05-11T00:55:11.470908Z","steps":["trace[717081512] 'process raft request' (duration: 105.942369ms)","trace[717081512] 'compare' (duration: 38.983485ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:55:11.471151Z","caller":"traceutil/trace.go:171","msg":"trace[1551043927] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"107.511321ms","start":"2026-05-11T00:55:11.363623Z","end":"2026-05-11T00:55:11.471134Z","steps":["trace[1551043927] 'process raft request' (duration: 107.234937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:55:19.684086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.96307ms","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-05-11T00:55:19.684211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.757024ms","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-05-11T00:55:19.684247Z","caller":"traceutil/trace.go:171","msg":"trace[1236445066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1940; }","duration":"107.157794ms","start":"2026-05-11T00:55:19.577062Z","end":"2026-05-11T00:55:19.68422Z","steps":["trace[1236445066] 'range keys from in-memory index tree' (duration: 106.721255ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:55:19.684307Z","caller":"traceutil/trace.go:171","msg":"trace[1739371266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1940; }","duration":"126.910137ms","start":"2026-05-11T00:55:19.557371Z","end":"2026-05-11T00:55:19.684281Z","steps":["trace[1739371266] 'range keys from in-memory index tree' (duration: 126.546719ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:55:53.715398Z","caller":"traceutil/trace.go:171","msg":"trace[888892646] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"270.809495ms","start":"2026-05-11T00:55:53.444531Z","end":"2026-05-11T00:55:53.715341Z","steps":["trace[888892646] 'process raft request' (duration: 270.608727ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:55:53.848087Z","caller":"traceutil/trace.go:171","msg":"trace[49332635] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"126.339739ms","start":"2026-05-11T00:55:53.721719Z","end":"2026-05-11T00:55:53.848058Z","steps":["trace[49332635] 'process raft request' (duration: 124.819852ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:57:03.617169Z","caller":"traceutil/trace.go:171","msg":"trace[2130965802] transaction","detail":"{read_only:false; response_revision:2770; number_of_response:1; }","duration":"107.734451ms","start":"2026-05-11T00:57:03.509412Z","end":"2026-05-11T00:57:03.617146Z","steps":["trace[2130965802] 'process raft request' (duration: 107.612054ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:57:03.768566Z","caller":"traceutil/trace.go:171","msg":"trace[924338208] transaction","detail":"{read_only:false; response_revision:2771; number_of_response:1; }","duration":"219.690999ms","start":"2026-05-11T00:57:03.548846Z","end":"2026-05-11T00:57:03.768537Z","steps":["trace[924338208] 'process raft request' (duration: 219.468157ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:25.444486Z","caller":"traceutil/trace.go:171","msg":"trace[579917736] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4468; }","duration":"142.94537ms","start":"2026-05-11T00:59:25.301511Z","end":"2026-05-11T00:59:25.444457Z","steps":["trace[579917736] 'process raft request' (duration: 134.914083ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:31.142849Z","caller":"traceutil/trace.go:171","msg":"trace[154985942] transaction","detail":"{read_only:false; response_revision:4540; number_of_response:1; }","duration":"236.185704ms","start":"2026-05-11T00:59:30.906628Z","end":"2026-05-11T00:59:31.142814Z","steps":["trace[154985942] 'process raft request' (duration: 156.323528ms)","trace[154985942] 'compare' (duration: 79.566549ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:31.142927Z","caller":"traceutil/trace.go:171","msg":"trace[178797121] linearizableReadLoop","detail":"{readStateIndex:4734; appliedIndex:4732; }","duration":"233.061387ms","start":"2026-05-11T00:59:30.909847Z","end":"2026-05-11T00:59:31.142909Z","steps":["trace[178797121] 'read index received' (duration: 153.119262ms)","trace[178797121] 'applied index is now lower than readState.Index' (duration: 79.940855ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:31.143177Z","caller":"traceutil/trace.go:171","msg":"trace[1550859906] transaction","detail":"{read_only:false; response_revision:4541; number_of_response:1; }","duration":"234.564441ms","start":"2026-05-11T00:59:30.90859Z","end":"2026-05-11T00:59:31.143154Z","steps":["trace[1550859906] 'process raft request' (duration: 234.153686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:31.143252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.387094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-11T00:59:31.143276Z","caller":"traceutil/trace.go:171","msg":"trace[894704541] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4541; }","duration":"233.444052ms","start":"2026-05-11T00:59:30.909826Z","end":"2026-05-11T00:59:31.14327Z","steps":["trace[894704541] 'agreement among raft nodes before linearized reading' (duration: 233.145455ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:31.427071Z","caller":"traceutil/trace.go:171","msg":"trace[1697146538] transaction","detail":"{read_only:false; response_revision:4545; number_of_response:1; }","duration":"103.048134ms","start":"2026-05-11T00:59:31.323998Z","end":"2026-05-11T00:59:31.427046Z","steps":["trace[1697146538] 'process raft request' (duration: 102.980574ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:31.427102Z","caller":"traceutil/trace.go:171","msg":"trace[495162104] transaction","detail":"{read_only:false; response_revision:4544; number_of_response:1; }","duration":"112.291702ms","start":"2026-05-11T00:59:31.314783Z","end":"2026-05-11T00:59:31.427075Z","steps":["trace[495162104] 'process raft request' (duration: 81.680347ms)","trace[495162104] 'compare' (duration: 30.305369ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:59:31.427284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.351861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12974"} {"level":"info","ts":"2026-05-11T00:59:31.427333Z","caller":"traceutil/trace.go:171","msg":"trace[1602234298] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4545; }","duration":"104.439911ms","start":"2026-05-11T00:59:31.322883Z","end":"2026-05-11T00:59:31.427323Z","steps":["trace[1602234298] 'agreement among raft nodes before linearized reading' (duration: 104.285582ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:31.427149Z","caller":"traceutil/trace.go:171","msg":"trace[1870110915] linearizableReadLoop","detail":"{readStateIndex:4737; appliedIndex:4736; }","duration":"104.209103ms","start":"2026-05-11T00:59:31.322916Z","end":"2026-05-11T00:59:31.427125Z","steps":["trace[1870110915] 'read index received' (duration: 73.561818ms)","trace[1870110915] 'applied index is now lower than readState.Index' (duration: 30.645365ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:31.698921Z","caller":"traceutil/trace.go:171","msg":"trace[416914400] transaction","detail":"{read_only:false; response_revision:4548; number_of_response:1; }","duration":"221.026101ms","start":"2026-05-11T00:59:31.477853Z","end":"2026-05-11T00:59:31.698879Z","steps":["trace[416914400] 'process raft request' (duration: 199.10703ms)","trace[416914400] 'compare' (duration: 21.315008ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:31.699235Z","caller":"traceutil/trace.go:171","msg":"trace[35105463] transaction","detail":"{read_only:false; response_revision:4549; number_of_response:1; }","duration":"220.940811ms","start":"2026-05-11T00:59:31.478247Z","end":"2026-05-11T00:59:31.699188Z","steps":["trace[35105463] 'process raft request' (duration: 220.436886ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:31.699251Z","caller":"traceutil/trace.go:171","msg":"trace[301000326] transaction","detail":"{read_only:false; response_revision:4550; number_of_response:1; }","duration":"220.360447ms","start":"2026-05-11T00:59:31.478857Z","end":"2026-05-11T00:59:31.699218Z","steps":["trace[301000326] 'process raft request' (duration: 220.02107ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:00:02.887806Z","caller":"traceutil/trace.go:171","msg":"trace[1494160307] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"299.457258ms","start":"2026-05-11T01:00:02.588332Z","end":"2026-05-11T01:00:02.887789Z","steps":["trace[1494160307] 'process raft request' (duration: 299.358078ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:00:02.888048Z","caller":"traceutil/trace.go:171","msg":"trace[1201395385] linearizableReadLoop","detail":"{readStateIndex:5177; appliedIndex:5177; }","duration":"192.052431ms","start":"2026-05-11T01:00:02.695977Z","end":"2026-05-11T01:00:02.888029Z","steps":["trace[1201395385] 'read index received' (duration: 192.044891ms)","trace[1201395385] 'applied index is now lower than readState.Index' (duration: 6.3µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:00:02.888229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.243923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-05-11T01:00:02.888271Z","caller":"traceutil/trace.go:171","msg":"trace[1213393947] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4967; }","duration":"192.321853ms","start":"2026-05-11T01:00:02.695935Z","end":"2026-05-11T01:00:02.888257Z","steps":["trace[1213393947] 'agreement among raft nodes before linearized reading' (duration: 192.185532ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:00:02.889407Z","caller":"traceutil/trace.go:171","msg":"trace[1748582148] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"141.676045ms","start":"2026-05-11T01:00:02.747706Z","end":"2026-05-11T01:00:02.889382Z","steps":["trace[1748582148] 'process raft request' (duration: 141.117972ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:03:03.27399Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3264} {"level":"info","ts":"2026-05-11T01:03:03.395165Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3264,"took":"118.862596ms","hash":2674748282,"current-db-size-bytes":59457536,"current-db-size":"60 MB","current-db-size-in-use-bytes":32522240,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T01:03:03.395241Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2674748282,"revision":3264,"compact-revision":-1}