{"level":"warn","ts":"2026-05-06T17:50:46.007565Z","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-06T17:50:46.00768Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240: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.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-06T17:50:46.007754Z","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-06T17:50:46.007767Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-06T17:50:46.007815Z","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-06T17:50:46.008498Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-05-06T17:50:46.008591Z","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.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-06T17:50:46.01379Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.925968ms"} {"level":"info","ts":"2026-05-06T17:50:46.022548Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-05-06T17:50:46.022634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T17:50:46.022669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-05-06T17:50:46.02268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T17:50:46.022689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-05-06T17:50:46.022763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-05-06T17:50:46.02779Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T17:50:46.029306Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T17:50:46.030313Z","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-06T17:50:46.032522Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T17:50:46.034254Z","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-06T17:50:46.035108Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T17:50:46.035686Z","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-06T17:50:46.035715Z","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-06T17:50:46.035797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-05-06T17:50:46.03636Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-06T17:50:46.037164Z","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-06T17:50:46.037396Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T17:50:46.037439Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T17:50:46.037334Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-06T17:50:46.037527Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-06T17:50:46.324049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T17:50:46.324111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T17:50:46.324157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-05-06T17:50:46.324177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-05-06T17:50:46.324189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-06T17:50:46.324205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-05-06T17:50:46.324224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-06T17:50:46.324983Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T17:50:46.325125Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T17:50:46.325167Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T17:50:46.325404Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T17:50:46.325196Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T17:50:46.325645Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T17:50:46.327333Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-05-06T17:50:46.328836Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T17:50:46.329005Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T17:50:46.329158Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T17:50:46.329215Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T17:51:01.502413Z","caller":"traceutil/trace.go:171","msg":"trace[1570744174] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:377; }","duration":"173.557148ms","start":"2026-05-06T17:51:01.328831Z","end":"2026-05-06T17:51:01.502389Z","steps":["trace[1570744174] 'read index received' (duration: 173.287075ms)","trace[1570744174] 'applied index is now lower than readState.Index' (duration: 268.573µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T17:51:01.502505Z","caller":"traceutil/trace.go:171","msg":"trace[1579171527] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"246.195475ms","start":"2026-05-06T17:51:01.256262Z","end":"2026-05-06T17:51:01.502457Z","steps":["trace[1579171527] 'process raft request' (duration: 245.979792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:51:01.502558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.70233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T17:51:01.502652Z","caller":"traceutil/trace.go:171","msg":"trace[1631926260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:366; }","duration":"173.824041ms","start":"2026-05-06T17:51:01.328795Z","end":"2026-05-06T17:51:01.502619Z","steps":["trace[1631926260] 'agreement among raft nodes before linearized reading' (duration: 173.71202ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T17:51:20.132673Z","caller":"traceutil/trace.go:171","msg":"trace[1034123492] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"131.624231ms","start":"2026-05-06T17:51:20.001017Z","end":"2026-05-06T17:51:20.132641Z","steps":["trace[1034123492] 'process raft request' (duration: 131.442529ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T17:51:20.150733Z","caller":"traceutil/trace.go:171","msg":"trace[1510494055] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"129.87326ms","start":"2026-05-06T17:51:20.020838Z","end":"2026-05-06T17:51:20.150712Z","steps":["trace[1510494055] 'process raft request' (duration: 129.603987ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T17:51:26.141845Z","caller":"traceutil/trace.go:171","msg":"trace[1563198068] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"243.854684ms","start":"2026-05-06T17:51:25.897963Z","end":"2026-05-06T17:51:26.141817Z","steps":["trace[1563198068] 'process raft request' (duration: 243.708632ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T17:51:26.327379Z","caller":"traceutil/trace.go:171","msg":"trace[996095494] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"390.164896ms","start":"2026-05-06T17:51:25.937148Z","end":"2026-05-06T17:51:26.327313Z","steps":["trace[996095494] 'read index received' (duration: 204.654252ms)","trace[996095494] 'applied index is now lower than readState.Index' (duration: 185.509204ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T17:51:26.327476Z","caller":"traceutil/trace.go:171","msg":"trace[1544208995] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"400.597319ms","start":"2026-05-06T17:51:25.926842Z","end":"2026-05-06T17:51:26.327439Z","steps":["trace[1544208995] 'process raft request' (duration: 396.819464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:51:26.327609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.444369ms","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-06T17:51:26.327664Z","caller":"traceutil/trace.go:171","msg":"trace[1336915824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"390.53203ms","start":"2026-05-06T17:51:25.937114Z","end":"2026-05-06T17:51:26.327646Z","steps":["trace[1336915824] 'agreement among raft nodes before linearized reading' (duration: 390.325038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:51:26.327697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T17:51:25.937098Z","time spent":"390.59231ms","remote":"127.0.0.1:35358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T17:51:26.328102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.96826ms","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-06T17:51:26.328136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T17:51:25.926814Z","time spent":"400.74548ms","remote":"127.0.0.1:35208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T17:51:26.328171Z","caller":"traceutil/trace.go:171","msg":"trace[365746118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"305.064161ms","start":"2026-05-06T17:51:26.023081Z","end":"2026-05-06T17:51:26.328145Z","steps":["trace[365746118] 'agreement among raft nodes before linearized reading' (duration: 304.915709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:51:26.328224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T17:51:26.023059Z","time spent":"305.148491ms","remote":"127.0.0.1:35358","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":"info","ts":"2026-05-06T17:51:45.540825Z","caller":"traceutil/trace.go:171","msg":"trace[1900378642] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"100.484452ms","start":"2026-05-06T17:51:45.440315Z","end":"2026-05-06T17:51:45.540799Z","steps":["trace[1900378642] 'process raft request' (duration: 100.31592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:53:20.599012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.529613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T17:53:20.599112Z","caller":"traceutil/trace.go:171","msg":"trace[1354627568] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1982; }","duration":"254.698328ms","start":"2026-05-06T17:53:20.344391Z","end":"2026-05-06T17:53:20.599089Z","steps":["trace[1354627568] 'count revisions from in-memory index tree' (duration: 254.409349ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T17:53:20.599272Z","caller":"traceutil/trace.go:171","msg":"trace[904771342] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"103.010508ms","start":"2026-05-06T17:53:20.496249Z","end":"2026-05-06T17:53:20.599259Z","steps":["trace[904771342] 'process raft request' (duration: 95.227331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:53:20.59928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.232458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-06T17:53:20.599406Z","caller":"traceutil/trace.go:171","msg":"trace[182520826] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1982; }","duration":"242.427994ms","start":"2026-05-06T17:53:20.356952Z","end":"2026-05-06T17:53:20.59938Z","steps":["trace[182520826] 'range keys from in-memory index tree' (duration: 241.93126ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T17:53:42.500674Z","caller":"traceutil/trace.go:171","msg":"trace[1542954055] linearizableReadLoop","detail":"{readStateIndex:2187; appliedIndex:2186; }","duration":"111.075818ms","start":"2026-05-06T17:53:42.389562Z","end":"2026-05-06T17:53:42.500638Z","steps":["trace[1542954055] 'read index received' (duration: 110.827322ms)","trace[1542954055] 'applied index is now lower than readState.Index' (duration: 246.046µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T17:53:42.50092Z","caller":"traceutil/trace.go:171","msg":"trace[1966594136] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"159.516802ms","start":"2026-05-06T17:53:42.341374Z","end":"2026-05-06T17:53:42.500891Z","steps":["trace[1966594136] 'process raft request' (duration: 159.089801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:53:42.500987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.397426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-06T17:53:42.501087Z","caller":"traceutil/trace.go:171","msg":"trace[1866980801] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2108; }","duration":"111.56829ms","start":"2026-05-06T17:53:42.389495Z","end":"2026-05-06T17:53:42.501064Z","steps":["trace[1866980801] 'agreement among raft nodes before linearized reading' (duration: 111.286393ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T17:53:42.844422Z","caller":"traceutil/trace.go:171","msg":"trace[1102829216] linearizableReadLoop","detail":"{readStateIndex:2188; appliedIndex:2187; }","duration":"145.879924ms","start":"2026-05-06T17:53:42.698519Z","end":"2026-05-06T17:53:42.844399Z","steps":["trace[1102829216] 'read index received' (duration: 145.672359ms)","trace[1102829216] 'applied index is now lower than readState.Index' (duration: 206.695µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T17:53:42.844528Z","caller":"traceutil/trace.go:171","msg":"trace[959526896] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"159.543953ms","start":"2026-05-06T17:53:42.684954Z","end":"2026-05-06T17:53:42.844498Z","steps":["trace[959526896] 'process raft request' (duration: 159.306367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:53:42.844704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.174592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-06T17:53:42.845175Z","caller":"traceutil/trace.go:171","msg":"trace[1828806565] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2109; }","duration":"146.707105ms","start":"2026-05-06T17:53:42.698452Z","end":"2026-05-06T17:53:42.845159Z","steps":["trace[1828806565] 'agreement among raft nodes before linearized reading' (duration: 146.066909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:53:43.034365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.639174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T17:53:43.034736Z","caller":"traceutil/trace.go:171","msg":"trace[342261977] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"169.949174ms","start":"2026-05-06T17:53:42.864643Z","end":"2026-05-06T17:53:43.034592Z","steps":["trace[342261977] 'process raft request' (duration: 58.829758ms)","trace[342261977] 'compare' (duration: 110.45912ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T17:53:43.690305Z","caller":"traceutil/trace.go:171","msg":"trace[1725160335] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"590.745482ms","start":"2026-05-06T17:53:43.099509Z","end":"2026-05-06T17:53:43.690255Z","steps":["trace[1725160335] 'process raft request' (duration: 590.574488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:53:43.690524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T17:53:43.099443Z","time spent":"590.943167ms","remote":"127.0.0.1:35358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T17:53:43.706145Z","caller":"traceutil/trace.go:171","msg":"trace[916735183] linearizableReadLoop","detail":"{readStateIndex:2194; appliedIndex:2192; }","duration":"379.7074ms","start":"2026-05-06T17:53:43.326402Z","end":"2026-05-06T17:53:43.706109Z","steps":["trace[916735183] 'read index received' (duration: 363.710654ms)","trace[916735183] 'applied index is now lower than readState.Index' (duration: 15.995436ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T17:53:43.706322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.905136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T17:53:43.706403Z","caller":"traceutil/trace.go:171","msg":"trace[2079827322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2115; }","duration":"380.034618ms","start":"2026-05-06T17:53:43.326349Z","end":"2026-05-06T17:53:43.706383Z","steps":["trace[2079827322] 'agreement among raft nodes before linearized reading' (duration: 379.884144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:53:43.706452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T17:53:43.326296Z","time spent":"380.148082ms","remote":"127.0.0.1:35132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T17:53:43.706224Z","caller":"traceutil/trace.go:171","msg":"trace[1406073838] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"606.297487ms","start":"2026-05-06T17:53:43.099881Z","end":"2026-05-06T17:53:43.706179Z","steps":["trace[1406073838] 'process raft request' (duration: 606.042791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:53:43.70666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T17:53:43.099852Z","time spent":"606.691987ms","remote":"127.0.0.1:35358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T17:53:43.706818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.121166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T17:53:43.706888Z","caller":"traceutil/trace.go:171","msg":"trace[1889656499] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2115; }","duration":"271.255669ms","start":"2026-05-06T17:53:43.435618Z","end":"2026-05-06T17:53:43.706874Z","steps":["trace[1889656499] 'agreement among raft nodes before linearized reading' (duration: 270.802188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:53:43.706954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.706488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-06T17:53:43.706999Z","caller":"traceutil/trace.go:171","msg":"trace[117470865] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2115; }","duration":"196.79233ms","start":"2026-05-06T17:53:43.510193Z","end":"2026-05-06T17:53:43.706986Z","steps":["trace[117470865] 'agreement among raft nodes before linearized reading' (duration: 196.617926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:53:44.131986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.873536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T17:53:44.1325Z","caller":"traceutil/trace.go:171","msg":"trace[1828366094] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"420.745497ms","start":"2026-05-06T17:53:43.711735Z","end":"2026-05-06T17:53:44.13248Z","steps":["trace[1828366094] 'process raft request' (duration: 54.305157ms)","trace[1828366094] 'compare' (duration: 365.437064ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T17:53:44.13257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T17:53:43.711708Z","time spent":"420.83017ms","remote":"127.0.0.1:35358","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-05-06T17:54:33.499034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.0256ms","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-05-06T17:54:33.499241Z","caller":"traceutil/trace.go:171","msg":"trace[946385603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2490; }","duration":"115.224123ms","start":"2026-05-06T17:54:33.383915Z","end":"2026-05-06T17:54:33.499139Z","steps":["trace[946385603] 'range keys from in-memory index tree' (duration: 114.795845ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T17:55:05.480062Z","caller":"traceutil/trace.go:171","msg":"trace[673482206] transaction","detail":"{read_only:false; response_revision:2665; number_of_response:1; }","duration":"101.302051ms","start":"2026-05-06T17:55:05.378733Z","end":"2026-05-06T17:55:05.480035Z","steps":["trace[673482206] 'process raft request' (duration: 90.559205ms)","trace[673482206] 'compare' (duration: 10.42187ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T17:57:23.35044Z","caller":"traceutil/trace.go:171","msg":"trace[880849275] transaction","detail":"{read_only:false; response_revision:3481; number_of_response:1; }","duration":"101.430914ms","start":"2026-05-06T17:57:23.248948Z","end":"2026-05-06T17:57:23.350379Z","steps":["trace[880849275] 'process raft request' (duration: 101.226811ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T17:59:01.339568Z","caller":"traceutil/trace.go:171","msg":"trace[1257317834] linearizableReadLoop","detail":"{readStateIndex:4762; appliedIndex:4761; }","duration":"269.12215ms","start":"2026-05-06T17:59:01.070422Z","end":"2026-05-06T17:59:01.339544Z","steps":["trace[1257317834] 'read index received' (duration: 729.329µs)","trace[1257317834] 'applied index is now lower than readState.Index' (duration: 268.391691ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T17:59:01.33979Z","caller":"traceutil/trace.go:171","msg":"trace[1462805045] transaction","detail":"{read_only:false; response_revision:4587; number_of_response:1; }","duration":"271.245619ms","start":"2026-05-06T17:59:01.068533Z","end":"2026-05-06T17:59:01.339778Z","steps":["trace[1462805045] 'process raft request' (duration: 270.821464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:59:01.3405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.802049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-05-06T17:59:01.340769Z","caller":"traceutil/trace.go:171","msg":"trace[1059724445] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4587; }","duration":"270.318227ms","start":"2026-05-06T17:59:01.070394Z","end":"2026-05-06T17:59:01.340712Z","steps":["trace[1059724445] 'agreement among raft nodes before linearized reading' (duration: 269.394814ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T17:59:01.342825Z","caller":"traceutil/trace.go:171","msg":"trace[1092972802] transaction","detail":"{read_only:false; response_revision:4588; number_of_response:1; }","duration":"249.278326ms","start":"2026-05-06T17:59:01.093525Z","end":"2026-05-06T17:59:01.342804Z","steps":["trace[1092972802] 'process raft request' (duration: 249.096723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:59:01.34285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.734389ms","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-05-06T17:59:01.342892Z","caller":"traceutil/trace.go:171","msg":"trace[2080075826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4588; }","duration":"266.80809ms","start":"2026-05-06T17:59:01.076073Z","end":"2026-05-06T17:59:01.342881Z","steps":["trace[2080075826] 'agreement among raft nodes before linearized reading' (duration: 266.621797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:59:01.343079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.63591ms","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-05-06T17:59:01.343123Z","caller":"traceutil/trace.go:171","msg":"trace[657443912] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4588; }","duration":"252.709951ms","start":"2026-05-06T17:59:01.090402Z","end":"2026-05-06T17:59:01.343112Z","steps":["trace[657443912] 'agreement among raft nodes before linearized reading' (duration: 252.609779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:59:07.992157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.713429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-05-06T17:59:07.992279Z","caller":"traceutil/trace.go:171","msg":"trace[1100569868] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4654; }","duration":"279.889592ms","start":"2026-05-06T17:59:07.712361Z","end":"2026-05-06T17:59:07.99225Z","steps":["trace[1100569868] 'range keys from in-memory index tree' (duration: 279.479116ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T17:59:07.992833Z","caller":"traceutil/trace.go:171","msg":"trace[1855756985] transaction","detail":"{read_only:false; response_revision:4655; number_of_response:1; }","duration":"164.785237ms","start":"2026-05-06T17:59:07.828016Z","end":"2026-05-06T17:59:07.992801Z","steps":["trace[1855756985] 'process raft request' (duration: 143.147978ms)","trace[1855756985] 'compare' (duration: 21.161892ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T17:59:07.992931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.539476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-84c8887c6f-s7fb8.18ad0bbfe56ca435\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-05-06T17:59:07.992985Z","caller":"traceutil/trace.go:171","msg":"trace[1686626698] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-84c8887c6f-s7fb8.18ad0bbfe56ca435; range_end:; response_count:1; response_revision:4654; }","duration":"280.055723ms","start":"2026-05-06T17:59:07.712916Z","end":"2026-05-06T17:59:07.992972Z","steps":["trace[1686626698] 'range keys from in-memory index tree' (duration: 279.468566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:59:07.992862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.556644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-06T17:59:07.993089Z","caller":"traceutil/trace.go:171","msg":"trace[792875295] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4654; }","duration":"216.02807ms","start":"2026-05-06T17:59:07.777016Z","end":"2026-05-06T17:59:07.993044Z","steps":["trace[792875295] 'range keys from in-memory index tree' (duration: 215.452002ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T17:59:08.412942Z","caller":"traceutil/trace.go:171","msg":"trace[76147066] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"141.069379ms","start":"2026-05-06T17:59:08.271836Z","end":"2026-05-06T17:59:08.412905Z","steps":["trace[76147066] 'process raft request' (duration: 140.584373ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T17:59:08.643975Z","caller":"traceutil/trace.go:171","msg":"trace[486083208] transaction","detail":"{read_only:false; response_revision:4674; number_of_response:1; }","duration":"100.893074ms","start":"2026-05-06T17:59:08.543042Z","end":"2026-05-06T17:59:08.643935Z","steps":["trace[486083208] 'process raft request' (duration: 100.59243ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T17:59:21.546281Z","caller":"traceutil/trace.go:171","msg":"trace[1242947091] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"176.98107ms","start":"2026-05-06T17:59:21.369256Z","end":"2026-05-06T17:59:21.546237Z","steps":["trace[1242947091] 'process raft request' (duration: 176.463883ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T17:59:53.97047Z","caller":"traceutil/trace.go:171","msg":"trace[853864901] linearizableReadLoop","detail":"{readStateIndex:5382; appliedIndex:5381; }","duration":"111.961187ms","start":"2026-05-06T17:59:53.858474Z","end":"2026-05-06T17:59:53.970435Z","steps":["trace[853864901] 'read index received' (duration: 111.787575ms)","trace[853864901] 'applied index is now lower than readState.Index' (duration: 171.892µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T17:59:53.970572Z","caller":"traceutil/trace.go:171","msg":"trace[522388404] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"154.023345ms","start":"2026-05-06T17:59:53.816497Z","end":"2026-05-06T17:59:53.970521Z","steps":["trace[522388404] 'process raft request' (duration: 153.711061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:59:53.970894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.922328ms","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-05-06T17:59:53.971269Z","caller":"traceutil/trace.go:171","msg":"trace[1217366276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5190; }","duration":"109.342194ms","start":"2026-05-06T17:59:53.861909Z","end":"2026-05-06T17:59:53.971251Z","steps":["trace[1217366276] 'agreement among raft nodes before linearized reading' (duration: 108.814027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T17:59:53.971139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.666266ms","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-05-06T17:59:53.97227Z","caller":"traceutil/trace.go:171","msg":"trace[1930236290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5190; }","duration":"113.812072ms","start":"2026-05-06T17:59:53.858424Z","end":"2026-05-06T17:59:53.972236Z","steps":["trace[1930236290] 'agreement among raft nodes before linearized reading' (duration: 112.202671ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T18:00:46.546741Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2882} {"level":"info","ts":"2026-05-06T18:00:46.634118Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2882,"took":"85.558762ms","hash":157901115,"current-db-size-bytes":52109312,"current-db-size":"52 MB","current-db-size-in-use-bytes":28577792,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-06T18:00:46.634241Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":157901115,"revision":2882,"compact-revision":-1} {"level":"info","ts":"2026-05-06T18:03:41.413735Z","caller":"traceutil/trace.go:171","msg":"trace[1874049594] transaction","detail":"{read_only:false; response_revision:6748; number_of_response:1; }","duration":"265.054405ms","start":"2026-05-06T18:03:41.148628Z","end":"2026-05-06T18:03:41.413683Z","steps":["trace[1874049594] 'process raft request' (duration: 200.640672ms)","trace[1874049594] 'compare' (duration: 64.109195ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T18:05:46.562972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5489} {"level":"info","ts":"2026-05-06T18:05:46.757208Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5489,"took":"192.84251ms","hash":4132175140,"current-db-size-bytes":52109312,"current-db-size":"52 MB","current-db-size-in-use-bytes":18169856,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-06T18:05:46.75727Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4132175140,"revision":5489,"compact-revision":2882}