{"level":"warn","ts":"2026-10-01T14:23:56.004283Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-01T14:23:56.004421Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-10-01T14:23:56.004513Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-01T14:23:56.004531Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-10-01T14:23:56.00455Z","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-10-01T14:23:56.005355Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-10-01T14:23:56.005454Z","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-10-01T14:23:56.012762Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.981827ms"} {"level":"info","ts":"2026-10-01T14:23:56.025577Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-10-01T14:23:56.02567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-10-01T14:23:56.025706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-10-01T14:23:56.025714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-01T14:23:56.025722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-10-01T14:23:56.025763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-10-01T14:23:56.03319Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-01T14:23:56.03507Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-01T14:23:56.036626Z","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-10-01T14:23:56.038628Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-01T14:23:56.038888Z","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-10-01T14:23:56.03882Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-01T14:23:56.038965Z","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-10-01T14:23:56.038976Z","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-10-01T14:23:56.041671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-10-01T14:23:56.043515Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-10-01T14:23:56.045448Z","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-10-01T14:23:56.045559Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-10-01T14:23:56.045602Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-10-01T14:23:56.045731Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-01T14:23:56.04578Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-01T14:23:56.926404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-10-01T14:23:56.926484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-10-01T14:23:56.926554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-10-01T14:23:56.926576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-10-01T14:23:56.926587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-10-01T14:23:56.926607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-10-01T14:23:56.926625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-10-01T14:23:56.928447Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-10-01T14:23:56.928501Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-01T14:23:56.92854Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-01T14:23:56.928875Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-01T14:23:56.928925Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-01T14:23:56.929062Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-01T14:23:56.930181Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-10-01T14:23:56.930293Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-01T14:23:56.93033Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-01T14:23:56.932827Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-01T14:23:56.933555Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-10-01T14:24:04.044032Z","caller":"traceutil/trace.go:171","msg":"trace[2107007332] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"103.369391ms","start":"2026-10-01T14:24:03.940621Z","end":"2026-10-01T14:24:04.04399Z","steps":["trace[2107007332] 'process raft request' (duration: 103.083373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:24:04.322359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.995983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T14:24:04.322508Z","caller":"traceutil/trace.go:171","msg":"trace[378440909] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:0; response_revision:327; }","duration":"193.210759ms","start":"2026-10-01T14:24:04.129255Z","end":"2026-10-01T14:24:04.322466Z","steps":["trace[378440909] 'range keys from in-memory index tree' (duration: 192.90998ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:24:04.447836Z","caller":"traceutil/trace.go:171","msg":"trace[1093903743] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"116.329231ms","start":"2026-10-01T14:24:04.331474Z","end":"2026-10-01T14:24:04.447804Z","steps":["trace[1093903743] 'process raft request' (duration: 116.120256ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:24:04.874845Z","caller":"traceutil/trace.go:171","msg":"trace[1581681678] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:340; }","duration":"185.828363ms","start":"2026-10-01T14:24:04.688982Z","end":"2026-10-01T14:24:04.87481Z","steps":["trace[1581681678] 'read index received' (duration: 103.538403ms)","trace[1581681678] 'applied index is now lower than readState.Index' (duration: 82.28861ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T14:24:04.875019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.012379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-10-01T14:24:04.875Z","caller":"traceutil/trace.go:171","msg":"trace[1213589770] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"216.525807ms","start":"2026-10-01T14:24:04.658426Z","end":"2026-10-01T14:24:04.874952Z","steps":["trace[1213589770] 'process raft request' (duration: 134.01306ms)","trace[1213589770] 'compare' (duration: 82.210989ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T14:24:04.87506Z","caller":"traceutil/trace.go:171","msg":"trace[1743327464] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:330; }","duration":"186.132261ms","start":"2026-10-01T14:24:04.688915Z","end":"2026-10-01T14:24:04.875047Z","steps":["trace[1743327464] 'agreement among raft nodes before linearized reading' (duration: 186.017438ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:24:39.223891Z","caller":"traceutil/trace.go:171","msg":"trace[1224856955] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"123.093885ms","start":"2026-10-01T14:24:39.100757Z","end":"2026-10-01T14:24:39.223851Z","steps":["trace[1224856955] 'read index received' (duration: 121.952337ms)","trace[1224856955] 'applied index is now lower than readState.Index' (duration: 1.140698ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T14:24:39.224015Z","caller":"traceutil/trace.go:171","msg":"trace[1379217155] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"277.401515ms","start":"2026-10-01T14:24:38.946603Z","end":"2026-10-01T14:24:39.224005Z","steps":["trace[1379217155] 'process raft request' (duration: 276.473642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:24:39.224439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.68562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-01T14:24:39.2245Z","caller":"traceutil/trace.go:171","msg":"trace[115519171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"123.792373ms","start":"2026-10-01T14:24:39.100679Z","end":"2026-10-01T14:24:39.224471Z","steps":["trace[115519171] 'agreement among raft nodes before linearized reading' (duration: 123.68201ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:24:39.777829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.947849ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T14:24:39.777991Z","caller":"traceutil/trace.go:171","msg":"trace[239273766] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:633; }","duration":"166.135273ms","start":"2026-10-01T14:24:39.611829Z","end":"2026-10-01T14:24:39.777964Z","steps":["trace[239273766] 'range keys from in-memory index tree' (duration: 165.921899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:24:39.778582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.512869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-01T14:24:39.778792Z","caller":"traceutil/trace.go:171","msg":"trace[797831039] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:653; }","duration":"199.028368ms","start":"2026-10-01T14:24:39.579743Z","end":"2026-10-01T14:24:39.778771Z","steps":["trace[797831039] 'read index received' (duration: 79.642227ms)","trace[797831039] 'applied index is now lower than readState.Index' (duration: 119.384851ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T14:24:39.778817Z","caller":"traceutil/trace.go:171","msg":"trace[1774556022] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"464.860702ms","start":"2026-10-01T14:24:39.313915Z","end":"2026-10-01T14:24:39.778776Z","steps":["trace[1774556022] 'process raft request' (duration: 345.445249ms)","trace[1774556022] 'compare' (duration: 118.354317ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T14:24:39.77905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.308265ms","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-10-01T14:24:39.779112Z","caller":"traceutil/trace.go:171","msg":"trace[72435482] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"199.393227ms","start":"2026-10-01T14:24:39.579684Z","end":"2026-10-01T14:24:39.779077Z","steps":["trace[72435482] 'agreement among raft nodes before linearized reading' (duration: 199.247853ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:24:39.779066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T14:24:39.313875Z","time spent":"465.117668ms","remote":"127.0.0.1:34272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-01T14:24:39.778904Z","caller":"traceutil/trace.go:171","msg":"trace[321118322] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"423.115345ms","start":"2026-10-01T14:24:39.355778Z","end":"2026-10-01T14:24:39.778893Z","steps":["trace[321118322] 'process raft request' (duration: 422.91865ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:24:39.780563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T14:24:39.355746Z","time spent":"424.383507ms","remote":"127.0.0.1:34012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T14:25:04.746471Z","caller":"traceutil/trace.go:171","msg":"trace[883058718] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"185.81474ms","start":"2026-10-01T14:25:04.560612Z","end":"2026-10-01T14:25:04.746427Z","steps":["trace[883058718] 'process raft request' (duration: 185.446991ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:26:44.454909Z","caller":"traceutil/trace.go:171","msg":"trace[1352998168] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"144.832945ms","start":"2026-10-01T14:26:44.310053Z","end":"2026-10-01T14:26:44.454885Z","steps":["trace[1352998168] 'process raft request' (duration: 144.635841ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:26:44.455574Z","caller":"traceutil/trace.go:171","msg":"trace[1574883683] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"145.362457ms","start":"2026-10-01T14:26:44.309454Z","end":"2026-10-01T14:26:44.454817Z","steps":["trace[1574883683] 'process raft request' (duration: 106.810568ms)","trace[1574883683] 'compare' (duration: 38.188711ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T14:26:44.45585Z","caller":"traceutil/trace.go:171","msg":"trace[2104779230] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"145.486411ms","start":"2026-10-01T14:26:44.310329Z","end":"2026-10-01T14:26:44.455815Z","steps":["trace[2104779230] 'process raft request' (duration: 145.301887ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:26:44.905944Z","caller":"traceutil/trace.go:171","msg":"trace[543532896] linearizableReadLoop","detail":"{readStateIndex:2060; appliedIndex:2059; }","duration":"146.442991ms","start":"2026-10-01T14:26:44.759458Z","end":"2026-10-01T14:26:44.905901Z","steps":["trace[543532896] 'read index received' (duration: 136.069917ms)","trace[543532896] 'applied index is now lower than readState.Index' (duration: 10.371444ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T14:26:44.905995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.21373ms","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-10-01T14:26:44.906118Z","caller":"traceutil/trace.go:171","msg":"trace[1910111906] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1983; }","duration":"334.391614ms","start":"2026-10-01T14:26:44.571678Z","end":"2026-10-01T14:26:44.90607Z","steps":["trace[1910111906] 'range keys from in-memory index tree' (duration: 333.881703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:26:44.906173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T14:26:44.571649Z","time spent":"334.511717ms","remote":"127.0.0.1:34012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-01T14:26:44.906289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.816259ms","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-10-01T14:26:44.906335Z","caller":"traceutil/trace.go:171","msg":"trace[213734453] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1984; }","duration":"146.929151ms","start":"2026-10-01T14:26:44.759389Z","end":"2026-10-01T14:26:44.906318Z","steps":["trace[213734453] 'agreement among raft nodes before linearized reading' (duration: 146.647345ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:26:44.906342Z","caller":"traceutil/trace.go:171","msg":"trace[249278803] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"189.013876ms","start":"2026-10-01T14:26:44.717275Z","end":"2026-10-01T14:26:44.906289Z","steps":["trace[249278803] 'process raft request' (duration: 178.238404ms)","trace[249278803] 'compare' (duration: 10.039236ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T14:26:45.203564Z","caller":"traceutil/trace.go:171","msg":"trace[1358686984] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"248.763894ms","start":"2026-10-01T14:26:44.954763Z","end":"2026-10-01T14:26:45.203527Z","steps":["trace[1358686984] 'process raft request' (duration: 207.967076ms)","trace[1358686984] 'compare' (duration: 40.608804ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T14:27:01.377907Z","caller":"traceutil/trace.go:171","msg":"trace[1279860335] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"101.852349ms","start":"2026-10-01T14:27:01.276024Z","end":"2026-10-01T14:27:01.377876Z","steps":["trace[1279860335] 'process raft request' (duration: 101.656365ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:27:17.664666Z","caller":"traceutil/trace.go:171","msg":"trace[216281783] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"235.896971ms","start":"2026-10-01T14:27:17.428747Z","end":"2026-10-01T14:27:17.664644Z","steps":["trace[216281783] 'process raft request' (duration: 235.757137ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:27:17.673402Z","caller":"traceutil/trace.go:171","msg":"trace[1327845106] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"229.481591ms","start":"2026-10-01T14:27:17.443117Z","end":"2026-10-01T14:27:17.672598Z","steps":["trace[1327845106] 'process raft request' (duration: 229.159255ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:27:24.752316Z","caller":"traceutil/trace.go:171","msg":"trace[1209993356] linearizableReadLoop","detail":"{readStateIndex:2335; appliedIndex:2334; }","duration":"204.473747ms","start":"2026-10-01T14:27:24.547816Z","end":"2026-10-01T14:27:24.752289Z","steps":["trace[1209993356] 'read index received' (duration: 204.220462ms)","trace[1209993356] 'applied index is now lower than readState.Index' (duration: 252.145µs)"],"step_count":2} {"level":"info","ts":"2026-10-01T14:27:24.752417Z","caller":"traceutil/trace.go:171","msg":"trace[2114938412] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"205.587372ms","start":"2026-10-01T14:27:24.54679Z","end":"2026-10-01T14:27:24.752377Z","steps":["trace[2114938412] 'process raft request' (duration: 205.333526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:27:24.752623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.033426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-01T14:27:24.752662Z","caller":"traceutil/trace.go:171","msg":"trace[1250525211] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2249; }","duration":"188.22779ms","start":"2026-10-01T14:27:24.564423Z","end":"2026-10-01T14:27:24.75265Z","steps":["trace[1250525211] 'agreement among raft nodes before linearized reading' (duration: 188.044996ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:27:24.752723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.883985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-01T14:27:24.752801Z","caller":"traceutil/trace.go:171","msg":"trace[1970029344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2249; }","duration":"205.030408ms","start":"2026-10-01T14:27:24.547749Z","end":"2026-10-01T14:27:24.75278Z","steps":["trace[1970029344] 'agreement among raft nodes before linearized reading' (duration: 204.712531ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:29:45.750396Z","caller":"traceutil/trace.go:171","msg":"trace[716911981] linearizableReadLoop","detail":"{readStateIndex:3217; appliedIndex:3215; }","duration":"119.417787ms","start":"2026-10-01T14:29:45.630952Z","end":"2026-10-01T14:29:45.75037Z","steps":["trace[716911981] 'read index received' (duration: 13.882554ms)","trace[716911981] 'applied index is now lower than readState.Index' (duration: 105.534023ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T14:29:45.750698Z","caller":"traceutil/trace.go:171","msg":"trace[1701565833] transaction","detail":"{read_only:false; response_revision:3101; number_of_response:1; }","duration":"122.433306ms","start":"2026-10-01T14:29:45.628247Z","end":"2026-10-01T14:29:45.75068Z","steps":["trace[1701565833] 'process raft request' (duration: 121.957535ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:29:45.75442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.071522ms","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":"warn","ts":"2026-10-01T14:29:45.754229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.937597ms","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-10-01T14:29:45.754752Z","caller":"traceutil/trace.go:171","msg":"trace[1496588112] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3101; }","duration":"120.837198ms","start":"2026-10-01T14:29:45.633892Z","end":"2026-10-01T14:29:45.75473Z","steps":["trace[1496588112] 'agreement among raft nodes before linearized reading' (duration: 117.742908ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:29:45.754953Z","caller":"traceutil/trace.go:171","msg":"trace[1048605496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3101; }","duration":"123.657653ms","start":"2026-10-01T14:29:45.630918Z","end":"2026-10-01T14:29:45.754575Z","steps":["trace[1048605496] 'agreement among raft nodes before linearized reading' (duration: 119.795196ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:33:56.962425Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2774} {"level":"info","ts":"2026-10-01T14:33:57.049708Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2774,"took":"86.138503ms","hash":3357425491,"current-db-size-bytes":42188800,"current-db-size":"42 MB","current-db-size-in-use-bytes":16642048,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-10-01T14:33:57.049774Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3357425491,"revision":2774,"compact-revision":-1} {"level":"info","ts":"2026-10-01T14:34:23.970577Z","caller":"traceutil/trace.go:171","msg":"trace[1889623605] linearizableReadLoop","detail":"{readStateIndex:5321; appliedIndex:5320; }","duration":"115.619907ms","start":"2026-10-01T14:34:23.854928Z","end":"2026-10-01T14:34:23.970548Z","steps":["trace[1889623605] 'read index received' (duration: 40.681461ms)","trace[1889623605] 'applied index is now lower than readState.Index' (duration: 74.937726ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T14:34:23.970759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.814811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9557"} {"level":"info","ts":"2026-10-01T14:34:23.970781Z","caller":"traceutil/trace.go:171","msg":"trace[616240309] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5122; }","duration":"115.863622ms","start":"2026-10-01T14:34:23.854911Z","end":"2026-10-01T14:34:23.970774Z","steps":["trace[616240309] 'agreement among raft nodes before linearized reading' (duration: 115.747899ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:34:23.970584Z","caller":"traceutil/trace.go:171","msg":"trace[2113197365] transaction","detail":"{read_only:false; response_revision:5122; number_of_response:1; }","duration":"116.829316ms","start":"2026-10-01T14:34:23.853726Z","end":"2026-10-01T14:34:23.970555Z","steps":["trace[2113197365] 'process raft request' (duration: 41.807477ms)","trace[2113197365] 'compare' (duration: 74.920206ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T14:34:23.974592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.347462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-10-01T14:34:23.974635Z","caller":"traceutil/trace.go:171","msg":"trace[562601239] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5123; }","duration":"119.460895ms","start":"2026-10-01T14:34:23.855162Z","end":"2026-10-01T14:34:23.974623Z","steps":["trace[562601239] 'agreement among raft nodes before linearized reading' (duration: 119.330752ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:34:23.974808Z","caller":"traceutil/trace.go:171","msg":"trace[609082323] transaction","detail":"{read_only:false; response_revision:5123; number_of_response:1; }","duration":"104.491292ms","start":"2026-10-01T14:34:23.870308Z","end":"2026-10-01T14:34:23.9748Z","steps":["trace[609082323] 'process raft request' (duration: 103.216193ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:34:23.975317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.11496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:46473"} {"level":"info","ts":"2026-10-01T14:34:23.975374Z","caller":"traceutil/trace.go:171","msg":"trace[2113731889] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:5123; }","duration":"120.186561ms","start":"2026-10-01T14:34:23.855175Z","end":"2026-10-01T14:34:23.975362Z","steps":["trace[2113731889] 'agreement among raft nodes before linearized reading' (duration: 118.599344ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:34:43.279551Z","caller":"traceutil/trace.go:171","msg":"trace[757514750] linearizableReadLoop","detail":"{readStateIndex:5668; appliedIndex:5667; }","duration":"142.264657ms","start":"2026-10-01T14:34:43.137271Z","end":"2026-10-01T14:34:43.279535Z","steps":["trace[757514750] 'read index received' (duration: 111.043362ms)","trace[757514750] 'applied index is now lower than readState.Index' (duration: 31.218645ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T14:34:43.279693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.409052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49176de9-08dc-48e3-993e-2d1a6c8e2925\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-10-01T14:34:43.279714Z","caller":"traceutil/trace.go:171","msg":"trace[313241072] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-49176de9-08dc-48e3-993e-2d1a6c8e2925; range_end:; response_count:1; response_revision:5459; }","duration":"142.474552ms","start":"2026-10-01T14:34:43.137232Z","end":"2026-10-01T14:34:43.279707Z","steps":["trace[313241072] 'agreement among raft nodes before linearized reading' (duration: 142.3697ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:34:43.279699Z","caller":"traceutil/trace.go:171","msg":"trace[2110292958] transaction","detail":"{read_only:false; response_revision:5459; number_of_response:1; }","duration":"271.095408ms","start":"2026-10-01T14:34:43.008562Z","end":"2026-10-01T14:34:43.279658Z","steps":["trace[2110292958] 'process raft request' (duration: 239.708969ms)","trace[2110292958] 'compare' (duration: 31.187225ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T14:34:43.504618Z","caller":"traceutil/trace.go:171","msg":"trace[2016448925] transaction","detail":"{read_only:false; response_revision:5461; number_of_response:1; }","duration":"161.442887ms","start":"2026-10-01T14:34:43.343143Z","end":"2026-10-01T14:34:43.504586Z","steps":["trace[2016448925] 'process raft request' (duration: 95.525357ms)","trace[2016448925] 'compare' (duration: 65.797967ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T14:34:43.730475Z","caller":"traceutil/trace.go:171","msg":"trace[1797266205] transaction","detail":"{read_only:false; response_revision:5467; number_of_response:1; }","duration":"131.729777ms","start":"2026-10-01T14:34:43.598713Z","end":"2026-10-01T14:34:43.730443Z","steps":["trace[1797266205] 'process raft request' (duration: 48.87024ms)","trace[1797266205] 'compare' (duration: 82.269383ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T14:34:43.825547Z","caller":"traceutil/trace.go:171","msg":"trace[1341349470] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"222.448254ms","start":"2026-10-01T14:34:43.603068Z","end":"2026-10-01T14:34:43.825516Z","steps":["trace[1341349470] 'process raft request' (duration: 222.238949ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:34:43.82585Z","caller":"traceutil/trace.go:171","msg":"trace[397781360] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"220.348926ms","start":"2026-10-01T14:34:43.60549Z","end":"2026-10-01T14:34:43.825838Z","steps":["trace[397781360] 'process raft request' (duration: 219.960557ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:34:43.825921Z","caller":"traceutil/trace.go:171","msg":"trace[536920072] linearizableReadLoop","detail":"{readStateIndex:5680; appliedIndex:5675; }","duration":"217.367708ms","start":"2026-10-01T14:34:43.608521Z","end":"2026-10-01T14:34:43.825889Z","steps":["trace[536920072] 'read index received' (duration: 39.154897ms)","trace[536920072] 'applied index is now lower than readState.Index' (duration: 178.211851ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T14:34:43.825973Z","caller":"traceutil/trace.go:171","msg":"trace[948914450] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"220.224833ms","start":"2026-10-01T14:34:43.605711Z","end":"2026-10-01T14:34:43.825936Z","steps":["trace[948914450] 'process raft request' (duration: 219.998498ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:34:43.826011Z","caller":"traceutil/trace.go:171","msg":"trace[1255452021] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5470; }","duration":"219.025076ms","start":"2026-10-01T14:34:43.606948Z","end":"2026-10-01T14:34:43.825973Z","steps":["trace[1255452021] 'process raft request' (duration: 218.890183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:34:43.826226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.650674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:1717"} {"level":"info","ts":"2026-10-01T14:34:43.826323Z","caller":"traceutil/trace.go:171","msg":"trace[542734623] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5470; }","duration":"217.826348ms","start":"2026-10-01T14:34:43.608474Z","end":"2026-10-01T14:34:43.826301Z","steps":["trace[542734623] 'agreement among raft nodes before linearized reading' (duration: 217.562142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:34:44.358031Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646846494162594189,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-01T14:34:44.502755Z","caller":"traceutil/trace.go:171","msg":"trace[1590023468] transaction","detail":"{read_only:false; response_revision:5471; number_of_response:1; }","duration":"667.991437ms","start":"2026-10-01T14:34:43.834717Z","end":"2026-10-01T14:34:44.502708Z","steps":["trace[1590023468] 'process raft request' (duration: 608.201819ms)","trace[1590023468] 'compare' (duration: 59.568943ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T14:34:44.502975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T14:34:43.83469Z","time spent":"668.170652ms","remote":"127.0.0.1:33848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":840,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-01T14:34:44.534829Z","caller":"traceutil/trace.go:171","msg":"trace[567382765] linearizableReadLoop","detail":"{readStateIndex:5684; appliedIndex:5680; }","duration":"677.674419ms","start":"2026-10-01T14:34:43.857123Z","end":"2026-10-01T14:34:44.534798Z","steps":["trace[567382765] 'read index received' (duration: 585.820846ms)","trace[567382765] 'applied index is now lower than readState.Index' (duration: 91.852413ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T14:34:44.535302Z","caller":"traceutil/trace.go:171","msg":"trace[2086322674] transaction","detail":"{read_only:false; response_revision:5472; number_of_response:1; }","duration":"695.209551ms","start":"2026-10-01T14:34:43.840073Z","end":"2026-10-01T14:34:44.535282Z","steps":["trace[2086322674] 'process raft request' (duration: 694.387512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:34:44.535348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.222442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-01T14:34:44.535408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.760136ms","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-10-01T14:34:44.53544Z","caller":"traceutil/trace.go:171","msg":"trace[1199517665] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5474; }","duration":"678.373886ms","start":"2026-10-01T14:34:43.857044Z","end":"2026-10-01T14:34:44.535418Z","steps":["trace[1199517665] 'agreement among raft nodes before linearized reading' (duration: 678.027848ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:34:44.535461Z","caller":"traceutil/trace.go:171","msg":"trace[827318093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5474; }","duration":"258.878599ms","start":"2026-10-01T14:34:44.276569Z","end":"2026-10-01T14:34:44.535447Z","steps":["trace[827318093] 'agreement among raft nodes before linearized reading' (duration: 258.685764ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:34:44.535493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T14:34:43.85703Z","time spent":"678.454947ms","remote":"127.0.0.1:34012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-01T14:34:44.535587Z","caller":"traceutil/trace.go:171","msg":"trace[297164594] transaction","detail":"{read_only:false; response_revision:5473; number_of_response:1; }","duration":"605.783024ms","start":"2026-10-01T14:34:43.929794Z","end":"2026-10-01T14:34:44.535577Z","steps":["trace[297164594] 'process raft request' (duration: 604.860452ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:34:44.535628Z","caller":"traceutil/trace.go:171","msg":"trace[170386838] transaction","detail":"{read_only:false; response_revision:5474; number_of_response:1; }","duration":"590.297478ms","start":"2026-10-01T14:34:43.945319Z","end":"2026-10-01T14:34:44.535617Z","steps":["trace[170386838] 'process raft request' (duration: 589.407678ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:34:44.535648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T14:34:43.929768Z","time spent":"605.851255ms","remote":"127.0.0.1:34012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T14:34:44.535701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T14:34:43.945305Z","time spent":"590.346349ms","remote":"127.0.0.1:33866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2581,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-01T14:34:44.535749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.466918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-01T14:34:44.535804Z","caller":"traceutil/trace.go:171","msg":"trace[1789026135] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5474; }","duration":"137.572971ms","start":"2026-10-01T14:34:44.398216Z","end":"2026-10-01T14:34:44.535789Z","steps":["trace[1789026135] 'agreement among raft nodes before linearized reading' (duration: 137.485748ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:34:44.535441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T14:34:43.840055Z","time spent":"695.297033ms","remote":"127.0.0.1:47254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2250,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T14:34:44.535994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.690915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49176de9-08dc-48e3-993e-2d1a6c8e2925\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-10-01T14:34:44.536036Z","caller":"traceutil/trace.go:171","msg":"trace[2030528919] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-49176de9-08dc-48e3-993e-2d1a6c8e2925; range_end:; response_count:1; response_revision:5474; }","duration":"251.778627ms","start":"2026-10-01T14:34:44.284248Z","end":"2026-10-01T14:34:44.536026Z","steps":["trace[2030528919] 'agreement among raft nodes before linearized reading' (duration: 251.707765ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:34:44.976344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.822113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-01T14:34:44.976423Z","caller":"traceutil/trace.go:171","msg":"trace[1848489943] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5478; }","duration":"367.954316ms","start":"2026-10-01T14:34:44.608448Z","end":"2026-10-01T14:34:44.976402Z","steps":["trace[1848489943] 'range keys from in-memory index tree' (duration: 367.615498ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:34:44.976463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T14:34:44.608429Z","time spent":"368.024007ms","remote":"127.0.0.1:33866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1365,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"warn","ts":"2026-10-01T14:34:44.977244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.507217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-01T14:34:44.977501Z","caller":"traceutil/trace.go:171","msg":"trace[1596948626] transaction","detail":"{read_only:false; response_revision:5479; number_of_response:1; }","duration":"367.138238ms","start":"2026-10-01T14:34:44.610337Z","end":"2026-10-01T14:34:44.977475Z","steps":["trace[1596948626] 'process raft request' (duration: 240.300853ms)","trace[1596948626] 'compare' (duration: 125.884363ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T14:34:44.977456Z","caller":"traceutil/trace.go:171","msg":"trace[1864787732] linearizableReadLoop","detail":"{readStateIndex:5689; appliedIndex:5688; }","duration":"147.744813ms","start":"2026-10-01T14:34:44.829689Z","end":"2026-10-01T14:34:44.977434Z","steps":["trace[1864787732] 'read index received' (duration: 20.944189ms)","trace[1864787732] 'applied index is now lower than readState.Index' (duration: 126.798424ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T14:34:44.977602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T14:34:44.610311Z","time spent":"367.23262ms","remote":"127.0.0.1:47260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3569,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-01T14:34:44.979332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.623546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-10-01T14:34:44.979567Z","caller":"traceutil/trace.go:171","msg":"trace[1259365463] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5479; }","duration":"149.860702ms","start":"2026-10-01T14:34:44.829656Z","end":"2026-10-01T14:34:44.979516Z","steps":["trace[1259365463] 'agreement among raft nodes before linearized reading' (duration: 147.901547ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:34:45.178554Z","caller":"traceutil/trace.go:171","msg":"trace[1887543974] transaction","detail":"{read_only:false; response_revision:5480; number_of_response:1; }","duration":"190.993124ms","start":"2026-10-01T14:34:44.987513Z","end":"2026-10-01T14:34:45.178506Z","steps":["trace[1887543974] 'process raft request' (duration: 185.077638ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:34:45.208055Z","caller":"traceutil/trace.go:171","msg":"trace[558619681] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"220.511389ms","start":"2026-10-01T14:34:44.987518Z","end":"2026-10-01T14:34:45.20803Z","steps":["trace[558619681] 'process raft request' (duration: 220.354457ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:34:45.208228Z","caller":"traceutil/trace.go:171","msg":"trace[1690129373] linearizableReadLoop","detail":"{readStateIndex:5695; appliedIndex:5689; }","duration":"217.634584ms","start":"2026-10-01T14:34:44.990546Z","end":"2026-10-01T14:34:45.208181Z","steps":["trace[1690129373] 'read index received' (duration: 182.055439ms)","trace[1690129373] 'applied index is now lower than readState.Index' (duration: 35.578295ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T14:34:45.208244Z","caller":"traceutil/trace.go:171","msg":"trace[529287657] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"220.169552ms","start":"2026-10-01T14:34:44.988055Z","end":"2026-10-01T14:34:45.208225Z","steps":["trace[529287657] 'process raft request' (duration: 219.973987ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:34:45.208307Z","caller":"traceutil/trace.go:171","msg":"trace[1118161265] transaction","detail":"{read_only:false; response_revision:5485; number_of_response:1; }","duration":"220.154042ms","start":"2026-10-01T14:34:44.988144Z","end":"2026-10-01T14:34:45.208298Z","steps":["trace[1118161265] 'process raft request' (duration: 219.916666ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:34:45.20837Z","caller":"traceutil/trace.go:171","msg":"trace[1791418615] transaction","detail":"{read_only:false; response_revision:5482; number_of_response:1; }","duration":"220.504279ms","start":"2026-10-01T14:34:44.987856Z","end":"2026-10-01T14:34:45.208361Z","steps":["trace[1791418615] 'process raft request' (duration: 220.11798ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:34:45.208437Z","caller":"traceutil/trace.go:171","msg":"trace[1644450286] transaction","detail":"{read_only:false; response_revision:5483; number_of_response:1; }","duration":"220.427188ms","start":"2026-10-01T14:34:44.988003Z","end":"2026-10-01T14:34:45.20843Z","steps":["trace[1644450286] 'process raft request' (duration: 220.002918ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:34:45.208499Z","caller":"traceutil/trace.go:171","msg":"trace[2099284256] transaction","detail":"{read_only:false; response_revision:5486; number_of_response:1; }","duration":"211.526354ms","start":"2026-10-01T14:34:44.996965Z","end":"2026-10-01T14:34:45.208492Z","steps":["trace[2099284256] 'process raft request' (duration: 211.153336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:34:45.208574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.002742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-10-01T14:34:45.208644Z","caller":"traceutil/trace.go:171","msg":"trace[1958324074] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5486; }","duration":"218.110234ms","start":"2026-10-01T14:34:44.990519Z","end":"2026-10-01T14:34:45.208629Z","steps":["trace[1958324074] 'agreement among raft nodes before linearized reading' (duration: 217.870169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T14:34:45.208685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.183605ms","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-10-01T14:34:45.208769Z","caller":"traceutil/trace.go:171","msg":"trace[601829504] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5486; }","duration":"183.328488ms","start":"2026-10-01T14:34:45.025421Z","end":"2026-10-01T14:34:45.20875Z","steps":["trace[601829504] 'agreement among raft nodes before linearized reading' (duration: 183.057322ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:38:57.000787Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4579} {"level":"warn","ts":"2026-10-01T14:38:57.287998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.122187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-01T14:38:57.288256Z","caller":"traceutil/trace.go:171","msg":"trace[1359558763] transaction","detail":"{read_only:false; response_revision:7318; number_of_response:1; }","duration":"268.018929ms","start":"2026-10-01T14:38:57.020207Z","end":"2026-10-01T14:38:57.288226Z","steps":["trace[1359558763] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/cilium-operator-resource-lock; req_size:472; } (duration: 260.914161ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T14:38:57.338805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4579,"took":"336.724041ms","hash":3197568046,"current-db-size-bytes":42188800,"current-db-size":"42 MB","current-db-size-in-use-bytes":33181696,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-01T14:38:57.338897Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3197568046,"revision":4579,"compact-revision":2774}