{"level":"warn","ts":"2026-05-29T14:02:50.843868Z","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-29T14:02:50.843949Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-29T14:02:50.844017Z","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-29T14:02:50.844028Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-29T14:02:50.844056Z","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-29T14:02:50.844671Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-05-29T14:02:50.844742Z","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":16,"max-cpu-available":16,"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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-29T14:02:50.848705Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.774799ms"} {"level":"info","ts":"2026-05-29T14:02:50.855029Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-05-29T14:02:50.855114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T14:02:50.855136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-05-29T14:02:50.855153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T14:02:50.855162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-05-29T14:02:50.855208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-05-29T14:02:50.861044Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T14:02:50.862796Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T14:02:50.864254Z","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-29T14:02:50.867217Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T14:02:50.867449Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T14:02:50.867624Z","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-29T14:02:50.867766Z","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-29T14:02:50.867787Z","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-29T14:02:50.868678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-05-29T14:02:50.868822Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-29T14:02:50.872977Z","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-29T14:02:50.873177Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-29T14:02:50.873264Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-29T14:02:50.873349Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T14:02:50.873408Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T14:02:51.755452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T14:02:51.755577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T14:02:51.755602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-05-29T14:02:51.755623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-05-29T14:02:51.755635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-29T14:02:51.755653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-05-29T14:02:51.75567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-29T14:02:51.757088Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T14:02:51.757956Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T14:02:51.758149Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T14:02:51.758241Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T14:02:51.758694Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T14:02:51.758837Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T14:02:51.758884Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T14:02:51.761451Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-05-29T14:02:51.761587Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T14:02:51.761635Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T14:02:51.761711Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T14:03:07.211007Z","caller":"traceutil/trace.go:171","msg":"trace[1018525100] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"141.048574ms","start":"2026-05-29T14:03:07.069927Z","end":"2026-05-29T14:03:07.210976Z","steps":["trace[1018525100] 'process raft request' (duration: 140.884571ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:07.546386Z","caller":"traceutil/trace.go:171","msg":"trace[655797075] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"132.044587ms","start":"2026-05-29T14:03:07.414305Z","end":"2026-05-29T14:03:07.546349Z","steps":["trace[655797075] 'process raft request' (duration: 131.787522ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:07.712673Z","caller":"traceutil/trace.go:171","msg":"trace[462953233] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"162.172532ms","start":"2026-05-29T14:03:07.550466Z","end":"2026-05-29T14:03:07.712639Z","steps":["trace[462953233] 'process raft request' (duration: 162.038759ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:07.723659Z","caller":"traceutil/trace.go:171","msg":"trace[479413437] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"172.6915ms","start":"2026-05-29T14:03:07.55093Z","end":"2026-05-29T14:03:07.723622Z","steps":["trace[479413437] 'process raft request' (duration: 172.474285ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:30.479373Z","caller":"traceutil/trace.go:171","msg":"trace[1120922381] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"125.260076ms","start":"2026-05-29T14:03:30.354064Z","end":"2026-05-29T14:03:30.479324Z","steps":["trace[1120922381] 'process raft request' (duration: 124.808076ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:31.12333Z","caller":"traceutil/trace.go:171","msg":"trace[749531908] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"222.343652ms","start":"2026-05-29T14:03:30.900919Z","end":"2026-05-29T14:03:31.123262Z","steps":["trace[749531908] 'process raft request' (duration: 221.518234ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:31.133522Z","caller":"traceutil/trace.go:171","msg":"trace[1227819535] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:621; }","duration":"222.993424ms","start":"2026-05-29T14:03:30.910473Z","end":"2026-05-29T14:03:31.133466Z","steps":["trace[1227819535] 'read index received' (duration: 211.930897ms)","trace[1227819535] 'applied index is now lower than readState.Index' (duration: 11.060817ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:03:31.133631Z","caller":"traceutil/trace.go:171","msg":"trace[1729690526] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"228.575639ms","start":"2026-05-29T14:03:30.90502Z","end":"2026-05-29T14:03:31.133595Z","steps":["trace[1729690526] 'process raft request' (duration: 223.487804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:31.133831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.325271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-05-29T14:03:31.133939Z","caller":"traceutil/trace.go:171","msg":"trace[1537859133] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:604; }","duration":"223.472834ms","start":"2026-05-29T14:03:30.910449Z","end":"2026-05-29T14:03:31.133922Z","steps":["trace[1537859133] 'agreement among raft nodes before linearized reading' (duration: 223.166388ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:36.676275Z","caller":"traceutil/trace.go:171","msg":"trace[592962081] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"104.624531ms","start":"2026-05-29T14:03:36.571611Z","end":"2026-05-29T14:03:36.676236Z","steps":["trace[592962081] 'process raft request' (duration: 50.197221ms)","trace[592962081] 'compare' (duration: 54.225016ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:03:37.200948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.511898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T14:03:37.201155Z","caller":"traceutil/trace.go:171","msg":"trace[307096511] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"472.148085ms","start":"2026-05-29T14:03:36.728971Z","end":"2026-05-29T14:03:37.201119Z","steps":["trace[307096511] 'process raft request' (duration: 114.863511ms)","trace[307096511] 'compare' (duration: 356.295194ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:03:37.201307Z","caller":"traceutil/trace.go:171","msg":"trace[1058902324] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"471.548513ms","start":"2026-05-29T14:03:36.729736Z","end":"2026-05-29T14:03:37.201284Z","steps":["trace[1058902324] 'process raft request' (duration: 471.39705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:37.201395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:36.728951Z","time spent":"472.349989ms","remote":"127.0.0.1:48278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T14:03:37.201538Z","caller":"traceutil/trace.go:171","msg":"trace[1320025773] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:663; }","duration":"320.246272ms","start":"2026-05-29T14:03:36.881247Z","end":"2026-05-29T14:03:37.201493Z","steps":["trace[1320025773] 'read index received' (duration: 51.651µs)","trace[1320025773] 'applied index is now lower than readState.Index' (duration: 320.190711ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:03:37.201568Z","caller":"traceutil/trace.go:171","msg":"trace[337131099] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"470.86783ms","start":"2026-05-29T14:03:36.73068Z","end":"2026-05-29T14:03:37.201548Z","steps":["trace[337131099] 'process raft request' (duration: 470.544043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:37.201635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:36.72971Z","time spent":"471.646106ms","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:03:37.201668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:36.730673Z","time spent":"470.941991ms","remote":"127.0.0.1:48402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:03:37.201758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.133076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:03:37.201817Z","caller":"traceutil/trace.go:171","msg":"trace[848942861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"168.244969ms","start":"2026-05-29T14:03:37.033554Z","end":"2026-05-29T14:03:37.201798Z","steps":["trace[848942861] 'agreement among raft nodes before linearized reading' (duration: 168.122876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:37.202035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.724814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dmz9c\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-29T14:03:37.202193Z","caller":"traceutil/trace.go:171","msg":"trace[2082352456] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dmz9c; range_end:; response_count:1; response_revision:646; }","duration":"320.986699ms","start":"2026-05-29T14:03:36.881171Z","end":"2026-05-29T14:03:37.202158Z","steps":["trace[2082352456] 'agreement among raft nodes before linearized reading' (duration: 320.473787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:37.202267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:36.881149Z","time spent":"321.104171ms","remote":"127.0.0.1:48402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-dmz9c\" "} {"level":"info","ts":"2026-05-29T14:04:24.284689Z","caller":"traceutil/trace.go:171","msg":"trace[937596392] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"110.266367ms","start":"2026-05-29T14:04:24.174401Z","end":"2026-05-29T14:04:24.284667Z","steps":["trace[937596392] 'process raft request' (duration: 109.849268ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:10.72414Z","caller":"traceutil/trace.go:171","msg":"trace[1309798376] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1941; }","duration":"315.826666ms","start":"2026-05-29T14:05:10.408291Z","end":"2026-05-29T14:05:10.724118Z","steps":["trace[1309798376] 'read index received' (duration: 315.610022ms)","trace[1309798376] 'applied index is now lower than readState.Index' (duration: 215.914µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:05:10.724435Z","caller":"traceutil/trace.go:171","msg":"trace[298878634] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"334.54943ms","start":"2026-05-29T14:05:10.389872Z","end":"2026-05-29T14:05:10.724421Z","steps":["trace[298878634] 'process raft request' (duration: 334.104221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:10.724594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:10.389848Z","time spent":"334.621661ms","remote":"127.0.0.1:48402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:05:10.724639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.039987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-29T14:05:10.724659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.365607ms","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-05-29T14:05:10.724705Z","caller":"traceutil/trace.go:171","msg":"trace[1547823094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1873; }","duration":"171.1717ms","start":"2026-05-29T14:05:10.553514Z","end":"2026-05-29T14:05:10.724686Z","steps":["trace[1547823094] 'agreement among raft nodes before linearized reading' (duration: 170.979816ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:10.72472Z","caller":"traceutil/trace.go:171","msg":"trace[22433130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1873; }","duration":"316.469869ms","start":"2026-05-29T14:05:10.408234Z","end":"2026-05-29T14:05:10.724703Z","steps":["trace[22433130] 'agreement among raft nodes before linearized reading' (duration: 316.302056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:10.724762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:10.408213Z","time spent":"316.538421ms","remote":"127.0.0.1:48486","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-05-29T14:05:54.361196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.422521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b40e5af6feec31\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-05-29T14:05:54.361389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.949737ms","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-29T14:05:54.361453Z","caller":"traceutil/trace.go:171","msg":"trace[1021107096] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b40e5af6feec31; range_end:; response_count:1; response_revision:2169; }","duration":"117.739677ms","start":"2026-05-29T14:05:54.243699Z","end":"2026-05-29T14:05:54.361439Z","steps":["trace[1021107096] 'range keys from in-memory index tree' (duration: 117.256487ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:54.361474Z","caller":"traceutil/trace.go:171","msg":"trace[1603637207] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2169; }","duration":"161.07406ms","start":"2026-05-29T14:05:54.200376Z","end":"2026-05-29T14:05:54.36145Z","steps":["trace[1603637207] 'range keys from in-memory index tree' (duration: 160.781834ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:07:31.575795Z","caller":"traceutil/trace.go:171","msg":"trace[1958716284] transaction","detail":"{read_only:false; response_revision:2887; number_of_response:1; }","duration":"134.451462ms","start":"2026-05-29T14:07:31.441287Z","end":"2026-05-29T14:07:31.575739Z","steps":["trace[1958716284] 'process raft request' (duration: 134.109154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:07:31.899714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.605243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-29T14:07:31.899822Z","caller":"traceutil/trace.go:171","msg":"trace[571912550] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2889; }","duration":"177.790986ms","start":"2026-05-29T14:07:31.722008Z","end":"2026-05-29T14:07:31.899799Z","steps":["trace[571912550] 'count revisions from in-memory index tree' (duration: 177.479309ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:07:31.899871Z","caller":"traceutil/trace.go:171","msg":"trace[1348009186] transaction","detail":"{read_only:false; response_revision:2890; number_of_response:1; }","duration":"101.95852ms","start":"2026-05-29T14:07:31.797855Z","end":"2026-05-29T14:07:31.899813Z","steps":["trace[1348009186] 'process raft request' (duration: 88.871983ms)","trace[1348009186] 'compare' (duration: 12.481624ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:11:33.798751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.38113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:11:33.798861Z","caller":"traceutil/trace.go:171","msg":"trace[1640191460] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:5106; }","duration":"129.529053ms","start":"2026-05-29T14:11:33.669309Z","end":"2026-05-29T14:11:33.798838Z","steps":["trace[1640191460] 'count revisions from in-memory index tree' (duration: 129.276709ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:11:46.357799Z","caller":"traceutil/trace.go:171","msg":"trace[1244016943] transaction","detail":"{read_only:false; response_revision:5341; number_of_response:1; }","duration":"107.430786ms","start":"2026-05-29T14:11:46.250346Z","end":"2026-05-29T14:11:46.357776Z","steps":["trace[1244016943] 'process raft request' (duration: 55.615126ms)","trace[1244016943] 'compare' (duration: 51.725738ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:11:46.889844Z","caller":"traceutil/trace.go:171","msg":"trace[292452434] transaction","detail":"{read_only:false; response_revision:5345; number_of_response:1; }","duration":"281.971199ms","start":"2026-05-29T14:11:46.607843Z","end":"2026-05-29T14:11:46.889814Z","steps":["trace[292452434] 'process raft request' (duration: 281.724964ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:11:46.890123Z","caller":"traceutil/trace.go:171","msg":"trace[543207776] transaction","detail":"{read_only:false; response_revision:5346; number_of_response:1; }","duration":"282.163434ms","start":"2026-05-29T14:11:46.607932Z","end":"2026-05-29T14:11:46.890095Z","steps":["trace[543207776] 'process raft request' (duration: 281.821767ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:11:46.890726Z","caller":"traceutil/trace.go:171","msg":"trace[3035781] linearizableReadLoop","detail":"{readStateIndex:5546; appliedIndex:5542; }","duration":"100.521816ms","start":"2026-05-29T14:11:46.790141Z","end":"2026-05-29T14:11:46.890663Z","steps":["trace[3035781] 'read index received' (duration: 99.386824ms)","trace[3035781] 'applied index is now lower than readState.Index' (duration: 1.133682ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:11:46.890883Z","caller":"traceutil/trace.go:171","msg":"trace[1697033436] transaction","detail":"{read_only:false; response_revision:5348; number_of_response:1; }","duration":"282.461459ms","start":"2026-05-29T14:11:46.608407Z","end":"2026-05-29T14:11:46.890868Z","steps":["trace[1697033436] 'process raft request' (duration: 282.072842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:11:46.891192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.028556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-05-29T14:11:46.891233Z","caller":"traceutil/trace.go:171","msg":"trace[950132517] transaction","detail":"{read_only:false; response_revision:5347; number_of_response:1; }","duration":"283.112733ms","start":"2026-05-29T14:11:46.608072Z","end":"2026-05-29T14:11:46.891184Z","steps":["trace[950132517] 'process raft request' (duration: 281.919239ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:11:46.891276Z","caller":"traceutil/trace.go:171","msg":"trace[1314937741] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5348; }","duration":"101.165729ms","start":"2026-05-29T14:11:46.79009Z","end":"2026-05-29T14:11:46.891255Z","steps":["trace[1314937741] 'agreement among raft nodes before linearized reading' (duration: 100.960515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:11:53.695849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.468277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:11:53.696022Z","caller":"traceutil/trace.go:171","msg":"trace[465376722] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:5513; }","duration":"194.696932ms","start":"2026-05-29T14:11:53.501281Z","end":"2026-05-29T14:11:53.695978Z","steps":["trace[465376722] 'count revisions from in-memory index tree' (duration: 194.366555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:11:54.042735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.826828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T14:11:54.042943Z","caller":"traceutil/trace.go:171","msg":"trace[1893022964] linearizableReadLoop","detail":"{readStateIndex:5716; appliedIndex:5715; }","duration":"224.389173ms","start":"2026-05-29T14:11:53.818534Z","end":"2026-05-29T14:11:54.042923Z","steps":["trace[1893022964] 'read index received' (duration: 13.185577ms)","trace[1893022964] 'applied index is now lower than readState.Index' (duration: 211.202156ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:11:54.043058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.532776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:11:54.043131Z","caller":"traceutil/trace.go:171","msg":"trace[1443536287] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:5515; }","duration":"224.661819ms","start":"2026-05-29T14:11:53.818456Z","end":"2026-05-29T14:11:54.043117Z","steps":["trace[1443536287] 'agreement among raft nodes before linearized reading' (duration: 224.555097ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:11:54.043072Z","caller":"traceutil/trace.go:171","msg":"trace[1971458832] transaction","detail":"{read_only:false; response_revision:5515; number_of_response:1; }","duration":"340.369281ms","start":"2026-05-29T14:11:53.702658Z","end":"2026-05-29T14:11:54.043027Z","steps":["trace[1971458832] 'process raft request' (duration: 129.113684ms)","trace[1971458832] 'compare' (duration: 210.663035ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:11:54.04332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:11:53.702633Z","time spent":"340.581445ms","remote":"127.0.0.1:48402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3748,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:11:54.193379Z","caller":"traceutil/trace.go:171","msg":"trace[591657033] transaction","detail":"{read_only:false; response_revision:5516; number_of_response:1; }","duration":"138.586756ms","start":"2026-05-29T14:11:54.054765Z","end":"2026-05-29T14:11:54.193352Z","steps":["trace[591657033] 'process raft request' (duration: 131.8852ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:12:51.788581Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3000} {"level":"info","ts":"2026-05-29T14:12:51.867935Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3000,"took":"77.960086ms","hash":340762250,"current-db-size-bytes":57565184,"current-db-size":"58 MB","current-db-size-in-use-bytes":33931264,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-29T14:12:51.868023Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":340762250,"revision":3000,"compact-revision":-1} {"level":"info","ts":"2026-05-29T14:17:51.79456Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6078} {"level":"info","ts":"2026-05-29T14:17:51.893342Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6078,"took":"96.17212ms","hash":1934276330,"current-db-size-bytes":57565184,"current-db-size":"58 MB","current-db-size-in-use-bytes":21590016,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-29T14:17:51.893423Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1934276330,"revision":6078,"compact-revision":3000} {"level":"info","ts":"2026-05-29T14:18:01.916514Z","caller":"traceutil/trace.go:171","msg":"trace[1727436291] transaction","detail":"{read_only:false; response_revision:8355; number_of_response:1; }","duration":"156.74159ms","start":"2026-05-29T14:18:01.75975Z","end":"2026-05-29T14:18:01.916492Z","steps":["trace[1727436291] 'process raft request' (duration: 156.586296ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:18:14.480822Z","caller":"traceutil/trace.go:171","msg":"trace[1734221445] transaction","detail":"{read_only:false; response_revision:8420; number_of_response:1; }","duration":"151.27981ms","start":"2026-05-29T14:18:14.329515Z","end":"2026-05-29T14:18:14.480795Z","steps":["trace[1734221445] 'process raft request' (duration: 151.079166ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:18:14.508373Z","caller":"traceutil/trace.go:171","msg":"trace[1635257712] linearizableReadLoop","detail":"{readStateIndex:8713; appliedIndex:8711; }","duration":"146.414331ms","start":"2026-05-29T14:18:14.361914Z","end":"2026-05-29T14:18:14.508328Z","steps":["trace[1635257712] 'read index received' (duration: 118.733194ms)","trace[1635257712] 'applied index is now lower than readState.Index' (duration: 27.679247ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:18:14.508439Z","caller":"traceutil/trace.go:171","msg":"trace[1388433574] transaction","detail":"{read_only:false; response_revision:8421; number_of_response:1; }","duration":"175.972127ms","start":"2026-05-29T14:18:14.332417Z","end":"2026-05-29T14:18:14.508389Z","steps":["trace[1388433574] 'process raft request' (duration: 175.706641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:18:14.508559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.639486ms","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-29T14:18:14.508616Z","caller":"traceutil/trace.go:171","msg":"trace[1919328317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8421; }","duration":"146.734909ms","start":"2026-05-29T14:18:14.361869Z","end":"2026-05-29T14:18:14.508604Z","steps":["trace[1919328317] 'agreement among raft nodes before linearized reading' (duration: 146.578185ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:18:15.013871Z","caller":"traceutil/trace.go:171","msg":"trace[154491108] transaction","detail":"{read_only:false; response_revision:8425; number_of_response:1; }","duration":"152.300761ms","start":"2026-05-29T14:18:14.861544Z","end":"2026-05-29T14:18:15.013845Z","steps":["trace[154491108] 'process raft request' (duration: 142.83966ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:18:15.018535Z","caller":"traceutil/trace.go:171","msg":"trace[2078796746] linearizableReadLoop","detail":"{readStateIndex:8718; appliedIndex:8716; }","duration":"103.133929ms","start":"2026-05-29T14:18:14.915379Z","end":"2026-05-29T14:18:15.018513Z","steps":["trace[2078796746] 'read index received' (duration: 89.016064ms)","trace[2078796746] 'applied index is now lower than readState.Index' (duration: 14.117115ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:18:15.018687Z","caller":"traceutil/trace.go:171","msg":"trace[387026493] transaction","detail":"{read_only:false; response_revision:8426; number_of_response:1; }","duration":"146.26388ms","start":"2026-05-29T14:18:14.872396Z","end":"2026-05-29T14:18:15.018659Z","steps":["trace[387026493] 'process raft request' (duration: 145.997194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:18:15.018882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.478486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-29T14:18:15.018951Z","caller":"traceutil/trace.go:171","msg":"trace[1945869669] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:8426; }","duration":"103.563888ms","start":"2026-05-29T14:18:14.915371Z","end":"2026-05-29T14:18:15.018935Z","steps":["trace[1945869669] 'agreement among raft nodes before linearized reading' (duration: 103.233161ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:18:42.694854Z","caller":"traceutil/trace.go:171","msg":"trace[1323995682] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"118.163002ms","start":"2026-05-29T14:18:42.576669Z","end":"2026-05-29T14:18:42.694832Z","steps":["trace[1323995682] 'process raft request' (duration: 118.018799ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:18:42.710651Z","caller":"traceutil/trace.go:171","msg":"trace[263963191] transaction","detail":"{read_only:false; response_revision:8625; number_of_response:1; }","duration":"105.103748ms","start":"2026-05-29T14:18:42.605527Z","end":"2026-05-29T14:18:42.710631Z","steps":["trace[263963191] 'process raft request' (duration: 104.837923ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:18:46.223003Z","caller":"traceutil/trace.go:171","msg":"trace[251177380] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"101.713851ms","start":"2026-05-29T14:18:46.121264Z","end":"2026-05-29T14:18:46.222978Z","steps":["trace[251177380] 'process raft request' (duration: 101.572327ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:18:55.188086Z","caller":"traceutil/trace.go:171","msg":"trace[154160877] transaction","detail":"{read_only:false; response_revision:8734; number_of_response:1; }","duration":"119.676822ms","start":"2026-05-29T14:18:55.068379Z","end":"2026-05-29T14:18:55.188055Z","steps":["trace[154160877] 'process raft request' (duration: 119.474228ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:18:55.339207Z","caller":"traceutil/trace.go:171","msg":"trace[1901106843] linearizableReadLoop","detail":"{readStateIndex:9041; appliedIndex:9040; }","duration":"126.358187ms","start":"2026-05-29T14:18:55.212825Z","end":"2026-05-29T14:18:55.339183Z","steps":["trace[1901106843] 'read index received' (duration: 126.122193ms)","trace[1901106843] 'applied index is now lower than readState.Index' (duration: 235.004µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:18:55.339511Z","caller":"traceutil/trace.go:171","msg":"trace[517478479] transaction","detail":"{read_only:false; response_revision:8735; number_of_response:1; }","duration":"144.023264ms","start":"2026-05-29T14:18:55.195446Z","end":"2026-05-29T14:18:55.339469Z","steps":["trace[517478479] 'process raft request' (duration: 143.597155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:18:55.339854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.02506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T14:18:55.339901Z","caller":"traceutil/trace.go:171","msg":"trace[1217064750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8735; }","duration":"127.126142ms","start":"2026-05-29T14:18:55.212765Z","end":"2026-05-29T14:18:55.339891Z","steps":["trace[1217064750] 'agreement among raft nodes before linearized reading' (duration: 126.967249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:18:55.340111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.845853ms","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-29T14:18:55.340143Z","caller":"traceutil/trace.go:171","msg":"trace[220874970] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8735; }","duration":"110.927426ms","start":"2026-05-29T14:18:55.229208Z","end":"2026-05-29T14:18:55.340135Z","steps":["trace[220874970] 'agreement among raft nodes before linearized reading' (duration: 110.839224ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:18:55.859631Z","caller":"traceutil/trace.go:171","msg":"trace[1963558006] transaction","detail":"{read_only:false; response_revision:8739; number_of_response:1; }","duration":"143.726258ms","start":"2026-05-29T14:18:55.715868Z","end":"2026-05-29T14:18:55.859594Z","steps":["trace[1963558006] 'process raft request' (duration: 143.471932ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:19:10.974477Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023e6.wal"} {"level":"info","ts":"2026-05-29T14:20:35.643427Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-29T14:20:35.650926Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-29T14:20:35.651159Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-29T14:22:51.801187Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8172} {"level":"info","ts":"2026-05-29T14:22:51.862995Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8172,"took":"58.837784ms","hash":3737635829,"current-db-size-bytes":57565184,"current-db-size":"58 MB","current-db-size-in-use-bytes":29671424,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-29T14:22:51.863118Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3737635829,"revision":8172,"compact-revision":6078} {"level":"info","ts":"2026-05-29T14:23:35.429055Z","caller":"traceutil/trace.go:171","msg":"trace[1833632253] transaction","detail":"{read_only:false; response_revision:11223; number_of_response:1; }","duration":"105.458443ms","start":"2026-05-29T14:23:35.323567Z","end":"2026-05-29T14:23:35.429025Z","steps":["trace[1833632253] 'process raft request' (duration: 105.278749ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:23:52.194289Z","caller":"traceutil/trace.go:171","msg":"trace[380815789] transaction","detail":"{read_only:false; response_revision:11315; number_of_response:1; }","duration":"102.946902ms","start":"2026-05-29T14:23:52.091306Z","end":"2026-05-29T14:23:52.194253Z","steps":["trace[380815789] 'process raft request' (duration: 102.603675ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:23:52.49522Z","caller":"traceutil/trace.go:171","msg":"trace[1185714806] transaction","detail":"{read_only:false; response_revision:11317; number_of_response:1; }","duration":"110.084946ms","start":"2026-05-29T14:23:52.385107Z","end":"2026-05-29T14:23:52.495192Z","steps":["trace[1185714806] 'process raft request' (duration: 85.405529ms)","trace[1185714806] 'compare' (duration: 24.087445ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:25:57.734649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.353299ms","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-29T14:25:57.734754Z","caller":"traceutil/trace.go:171","msg":"trace[389123138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12171; }","duration":"341.521693ms","start":"2026-05-29T14:25:57.39321Z","end":"2026-05-29T14:25:57.734731Z","steps":["trace[389123138] 'range keys from in-memory index tree' (duration: 341.133605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:25:57.734733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.1813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-29T14:25:57.734792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.398564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T14:25:57.734909Z","caller":"traceutil/trace.go:171","msg":"trace[1719818838] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:12171; }","duration":"365.564836ms","start":"2026-05-29T14:25:57.369312Z","end":"2026-05-29T14:25:57.734877Z","steps":["trace[1719818838] 'count revisions from in-memory index tree' (duration: 365.314632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:25:57.734975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:25:57.369293Z","time spent":"365.667729ms","remote":"127.0.0.1:48542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":14,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-05-29T14:25:57.734829Z","caller":"traceutil/trace.go:171","msg":"trace[2090449001] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12171; }","duration":"297.342953ms","start":"2026-05-29T14:25:57.43746Z","end":"2026-05-29T14:25:57.734803Z","steps":["trace[2090449001] 'range keys from in-memory index tree' (duration: 296.955976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:25:57.735442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:25:57.393191Z","time spent":"341.818938ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-29T14:26:43.433191Z","caller":"traceutil/trace.go:171","msg":"trace[132663845] linearizableReadLoop","detail":"{readStateIndex:12866; appliedIndex:12865; }","duration":"280.208338ms","start":"2026-05-29T14:26:43.152946Z","end":"2026-05-29T14:26:43.433154Z","steps":["trace[132663845] 'read index received' (duration: 277.001544ms)","trace[132663845] 'applied index is now lower than readState.Index' (duration: 3.205684ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:26:43.433337Z","caller":"traceutil/trace.go:171","msg":"trace[978814409] transaction","detail":"{read_only:false; response_revision:12447; number_of_response:1; }","duration":"350.401872ms","start":"2026-05-29T14:26:43.082918Z","end":"2026-05-29T14:26:43.43332Z","steps":["trace[978814409] 'process raft request' (duration: 346.994553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:26:43.433459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:26:43.082892Z","time spent":"350.484683ms","remote":"127.0.0.1:48486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:26:43.433556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.582097ms","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-05-29T14:26:43.433953Z","caller":"traceutil/trace.go:171","msg":"trace[802442366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12447; }","duration":"280.987295ms","start":"2026-05-29T14:26:43.152939Z","end":"2026-05-29T14:26:43.433926Z","steps":["trace[802442366] 'agreement among raft nodes before linearized reading' (duration: 280.409323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:26:43.434798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.714016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-05-29T14:26:43.434883Z","caller":"traceutil/trace.go:171","msg":"trace[1946198022] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12448; }","duration":"257.834028ms","start":"2026-05-29T14:26:43.177029Z","end":"2026-05-29T14:26:43.434863Z","steps":["trace[1946198022] 'agreement among raft nodes before linearized reading' (duration: 257.619434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:26:43.435135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.241617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z5j48\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-29T14:26:43.435224Z","caller":"traceutil/trace.go:171","msg":"trace[390527063] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z5j48; range_end:; response_count:1; response_revision:12448; }","duration":"177.379899ms","start":"2026-05-29T14:26:43.257823Z","end":"2026-05-29T14:26:43.435203Z","steps":["trace[390527063] 'agreement among raft nodes before linearized reading' (duration: 177.089933ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:26:43.43561Z","caller":"traceutil/trace.go:171","msg":"trace[1292696359] transaction","detail":"{read_only:false; response_revision:12448; number_of_response:1; }","duration":"256.95063ms","start":"2026-05-29T14:26:43.177912Z","end":"2026-05-29T14:26:43.434863Z","steps":["trace[1292696359] 'process raft request' (duration: 256.629264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:26:43.435946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.663207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-29T14:26:43.436049Z","caller":"traceutil/trace.go:171","msg":"trace[1312185492] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:12448; }","duration":"107.846651ms","start":"2026-05-29T14:26:43.328176Z","end":"2026-05-29T14:26:43.436023Z","steps":["trace[1312185492] 'agreement among raft nodes before linearized reading' (duration: 106.702017ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:27:51.806714Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10765} {"level":"info","ts":"2026-05-29T14:27:51.902144Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10765,"took":"93.298966ms","hash":3059401872,"current-db-size-bytes":57565184,"current-db-size":"58 MB","current-db-size-in-use-bytes":28086272,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-29T14:27:51.902912Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3059401872,"revision":10765,"compact-revision":8172} {"level":"info","ts":"2026-05-29T14:30:27.655623Z","caller":"traceutil/trace.go:171","msg":"trace[162665275] transaction","detail":"{read_only:false; response_revision:13966; number_of_response:1; }","duration":"289.112374ms","start":"2026-05-29T14:30:27.366486Z","end":"2026-05-29T14:30:27.655598Z","steps":["trace[162665275] 'process raft request' (duration: 288.955061ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:30:27.657436Z","caller":"traceutil/trace.go:171","msg":"trace[1976580193] linearizableReadLoop","detail":"{readStateIndex:14442; appliedIndex:14442; }","duration":"245.177941ms","start":"2026-05-29T14:30:27.412243Z","end":"2026-05-29T14:30:27.65742Z","steps":["trace[1976580193] 'read index received' (duration: 245.170291ms)","trace[1976580193] 'applied index is now lower than readState.Index' (duration: 5.92µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:30:27.657547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.281453ms","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-05-29T14:30:27.657581Z","caller":"traceutil/trace.go:171","msg":"trace[1240974672] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:13966; }","duration":"245.370915ms","start":"2026-05-29T14:30:27.412199Z","end":"2026-05-29T14:30:27.65757Z","steps":["trace[1240974672] 'agreement among raft nodes before linearized reading' (duration: 245.281633ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:30:50.782868Z","caller":"traceutil/trace.go:171","msg":"trace[1901277639] transaction","detail":"{read_only:false; response_revision:14282; number_of_response:1; }","duration":"133.814061ms","start":"2026-05-29T14:30:50.649023Z","end":"2026-05-29T14:30:50.782837Z","steps":["trace[1901277639] 'process raft request' (duration: 117.619675ms)","trace[1901277639] 'compare' (duration: 15.939921ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:30:50.783107Z","caller":"traceutil/trace.go:171","msg":"trace[1643982238] transaction","detail":"{read_only:false; response_revision:14283; number_of_response:1; }","duration":"133.912203ms","start":"2026-05-29T14:30:50.649176Z","end":"2026-05-29T14:30:50.783088Z","steps":["trace[1643982238] 'process raft request' (duration: 133.620217ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:30:51.217981Z","caller":"traceutil/trace.go:171","msg":"trace[1197752920] linearizableReadLoop","detail":"{readStateIndex:14776; appliedIndex:14775; }","duration":"183.121252ms","start":"2026-05-29T14:30:51.034832Z","end":"2026-05-29T14:30:51.217953Z","steps":["trace[1197752920] 'read index received' (duration: 176.424158ms)","trace[1197752920] 'applied index is now lower than readState.Index' (duration: 6.696024ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:30:51.218035Z","caller":"traceutil/trace.go:171","msg":"trace[292893352] transaction","detail":"{read_only:false; response_revision:14284; number_of_response:1; }","duration":"229.2231ms","start":"2026-05-29T14:30:50.988772Z","end":"2026-05-29T14:30:51.217995Z","steps":["trace[292893352] 'process raft request' (duration: 222.521945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:30:51.218146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.300915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:30:51.218185Z","caller":"traceutil/trace.go:171","msg":"trace[2097552625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14284; }","duration":"183.354817ms","start":"2026-05-29T14:30:51.034818Z","end":"2026-05-29T14:30:51.218173Z","steps":["trace[2097552625] 'agreement among raft nodes before linearized reading' (duration: 183.277785ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:32:51.817574Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12868} {"level":"info","ts":"2026-05-29T14:32:51.894975Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12868,"took":"74.58733ms","hash":659237077,"current-db-size-bytes":57565184,"current-db-size":"58 MB","current-db-size-in-use-bytes":30466048,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-29T14:32:51.895053Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":659237077,"revision":12868,"compact-revision":10765} {"level":"info","ts":"2026-05-29T14:37:51.823973Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15312} {"level":"info","ts":"2026-05-29T14:37:51.919841Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15312,"took":"91.694124ms","hash":3551628145,"current-db-size-bytes":57565184,"current-db-size":"58 MB","current-db-size-in-use-bytes":31809536,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-29T14:37:51.919915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3551628145,"revision":15312,"compact-revision":12868} {"level":"info","ts":"2026-05-29T14:42:51.830689Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17579} {"level":"info","ts":"2026-05-29T14:42:51.930152Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17579,"took":"94.065682ms","hash":2627974145,"current-db-size-bytes":57565184,"current-db-size":"58 MB","current-db-size-in-use-bytes":26775552,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-29T14:42:51.930227Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2627974145,"revision":17579,"compact-revision":15312} {"level":"info","ts":"2026-05-29T14:43:27.979829Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-29T14:43:27.984933Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-29T14:43:27.98512Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}