{"level":"warn","ts":"2026-04-09T05:57:01.01997Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T05:57:01.020045Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-04-09T05:57:01.020106Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T05:57:01.02012Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-04-09T05:57:01.020138Z","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-04-09T05:57:01.02077Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-04-09T05:57:01.020843Z","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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-04-09T05:57:01.024298Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.243541ms"} {"level":"info","ts":"2026-04-09T05:57:01.031856Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-04-09T05:57:01.031957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T05:57:01.031979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-04-09T05:57:01.031994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T05:57:01.032006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-04-09T05:57:01.032047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-04-09T05:57:01.046959Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T05:57:01.049304Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T05:57:01.050462Z","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-04-09T05:57:01.053558Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T05:57:01.057723Z","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-04-09T05:57:01.057941Z","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-04-09T05:57:01.057985Z","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-04-09T05:57:01.058005Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T05:57:01.058826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-04-09T05:57:01.059364Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-04-09T05:57:01.059952Z","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-04-09T05:57:01.060212Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T05:57:01.060281Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T05:57:01.060186Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-04-09T05:57:01.060371Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-04-09T05:57:01.833159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T05:57:01.833232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T05:57:01.833275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-04-09T05:57:01.833295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-04-09T05:57:01.833306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-04-09T05:57:01.833327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-04-09T05:57:01.833347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-04-09T05:57:01.836155Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T05:57:01.836211Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T05:57:01.836186Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T05:57:01.836484Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T05:57:01.836504Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T05:57:01.836538Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:57:01.840077Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-04-09T05:57:01.840208Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:57:01.840304Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:57:01.840354Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:57:01.840398Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T05:57:13.224435Z","caller":"traceutil/trace.go:171","msg":"trace[2120869188] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"130.545897ms","start":"2026-04-09T05:57:13.093865Z","end":"2026-04-09T05:57:13.224411Z","steps":["trace[2120869188] 'process raft request' (duration: 130.354723ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:13.484152Z","caller":"traceutil/trace.go:171","msg":"trace[889475675] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:360; }","duration":"129.605107ms","start":"2026-04-09T05:57:13.354523Z","end":"2026-04-09T05:57:13.484128Z","steps":["trace[889475675] 'read index received' (duration: 129.410623ms)","trace[889475675] 'applied index is now lower than readState.Index' (duration: 193.414µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:57:13.484207Z","caller":"traceutil/trace.go:171","msg":"trace[1047999562] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"159.047271ms","start":"2026-04-09T05:57:13.325131Z","end":"2026-04-09T05:57:13.484178Z","steps":["trace[1047999562] 'process raft request' (duration: 158.860327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:13.484371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.78977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-09T05:57:13.48438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.508438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-k49lt\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-04-09T05:57:13.484468Z","caller":"traceutil/trace.go:171","msg":"trace[1546300212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:348; }","duration":"129.986375ms","start":"2026-04-09T05:57:13.354468Z","end":"2026-04-09T05:57:13.484454Z","steps":["trace[1546300212] 'agreement among raft nodes before linearized reading' (duration: 129.794921ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:13.484479Z","caller":"traceutil/trace.go:171","msg":"trace[1135456456] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-k49lt; range_end:; response_count:1; response_revision:348; }","duration":"105.675283ms","start":"2026-04-09T05:57:13.378791Z","end":"2026-04-09T05:57:13.484466Z","steps":["trace[1135456456] 'agreement among raft nodes before linearized reading' (duration: 105.493279ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:13.634185Z","caller":"traceutil/trace.go:171","msg":"trace[1355720208] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"143.285907ms","start":"2026-04-09T05:57:13.490613Z","end":"2026-04-09T05:57:13.633898Z","steps":["trace[1355720208] 'process raft request' (duration: 138.475472ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:13.764862Z","caller":"traceutil/trace.go:171","msg":"trace[144090455] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"170.314319ms","start":"2026-04-09T05:57:13.594504Z","end":"2026-04-09T05:57:13.764818Z","steps":["trace[144090455] 'process raft request' (duration: 170.254467ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:13.764913Z","caller":"traceutil/trace.go:171","msg":"trace[830248954] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"274.164192ms","start":"2026-04-09T05:57:13.490656Z","end":"2026-04-09T05:57:13.76482Z","steps":["trace[830248954] 'process raft request' (duration: 273.847555ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:13.765007Z","caller":"traceutil/trace.go:171","msg":"trace[258355413] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"271.175875ms","start":"2026-04-09T05:57:13.493794Z","end":"2026-04-09T05:57:13.76497Z","steps":["trace[258355413] 'process raft request' (duration: 270.896819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:32.788447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.906647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:57:32.788567Z","caller":"traceutil/trace.go:171","msg":"trace[790062820] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:561; }","duration":"144.535573ms","start":"2026-04-09T05:57:32.644016Z","end":"2026-04-09T05:57:32.788552Z","steps":["trace[790062820] 'read index received' (duration: 38.739557ms)","trace[790062820] 'applied index is now lower than readState.Index' (duration: 105.795016ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:57:32.788635Z","caller":"traceutil/trace.go:171","msg":"trace[820332305] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"149.371609ms","start":"2026-04-09T05:57:32.639223Z","end":"2026-04-09T05:57:32.788595Z","steps":["trace[820332305] 'process raft request' (duration: 43.794258ms)","trace[820332305] 'compare' (duration: 104.742373ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:57:32.788853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.821379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570105"} {"level":"info","ts":"2026-04-09T05:57:32.788925Z","caller":"traceutil/trace.go:171","msg":"trace[484939861] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:540; }","duration":"144.950442ms","start":"2026-04-09T05:57:32.643956Z","end":"2026-04-09T05:57:32.788907Z","steps":["trace[484939861] 'agreement among raft nodes before linearized reading' (duration: 144.638375ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:36.925243Z","caller":"traceutil/trace.go:171","msg":"trace[967792828] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"125.174858ms","start":"2026-04-09T05:57:36.800031Z","end":"2026-04-09T05:57:36.925206Z","steps":["trace[967792828] 'process raft request' (duration: 124.497373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:37.095857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.817952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:57:37.096017Z","caller":"traceutil/trace.go:171","msg":"trace[1560340398] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"118.169874ms","start":"2026-04-09T05:57:36.977806Z","end":"2026-04-09T05:57:37.095976Z","steps":["trace[1560340398] 'process raft request' (duration: 13.057895ms)","trace[1560340398] 'compare' (duration: 104.472705ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:57:37.369571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.229583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:57:37.369647Z","caller":"traceutil/trace.go:171","msg":"trace[181384228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"149.355976ms","start":"2026-04-09T05:57:37.220274Z","end":"2026-04-09T05:57:37.36963Z","steps":["trace[181384228] 'range keys from in-memory index tree' (duration: 149.11452ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:37.370392Z","caller":"traceutil/trace.go:171","msg":"trace[322149013] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"134.715566ms","start":"2026-04-09T05:57:37.235648Z","end":"2026-04-09T05:57:37.370364Z","steps":["trace[322149013] 'process raft request' (duration: 36.510928ms)","trace[322149013] 'compare' (duration: 97.86041ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:57:37.841909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.974701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:57:37.842007Z","caller":"traceutil/trace.go:171","msg":"trace[1370259712] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"406.033369ms","start":"2026-04-09T05:57:37.435958Z","end":"2026-04-09T05:57:37.841991Z","steps":["trace[1370259712] 'read index received' (duration: 115.923995ms)","trace[1370259712] 'applied index is now lower than readState.Index' (duration: 290.108384ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:57:37.842072Z","caller":"traceutil/trace.go:171","msg":"trace[1970453671] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"467.931522ms","start":"2026-04-09T05:57:37.37413Z","end":"2026-04-09T05:57:37.842062Z","steps":["trace[1970453671] 'process raft request' (duration: 177.728686ms)","trace[1970453671] 'compare' (duration: 289.880959ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:57:37.842123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:37.374115Z","time spent":"467.979804ms","remote":"127.0.0.1:44566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:57:37.84233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.364386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-479mq\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-09T05:57:37.842407Z","caller":"traceutil/trace.go:171","msg":"trace[1015593630] range","detail":"{range_begin:/registry/pods/kube-system/cilium-479mq; range_end:; response_count:1; response_revision:569; }","duration":"406.485969ms","start":"2026-04-09T05:57:37.435902Z","end":"2026-04-09T05:57:37.842388Z","steps":["trace[1015593630] 'agreement among raft nodes before linearized reading' (duration: 406.295975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:37.842449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:37.435881Z","time spent":"406.56072ms","remote":"127.0.0.1:44484","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-479mq\" "} {"level":"info","ts":"2026-04-09T05:57:37.849006Z","caller":"traceutil/trace.go:171","msg":"trace[640037185] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"366.165237ms","start":"2026-04-09T05:57:37.482823Z","end":"2026-04-09T05:57:37.848989Z","steps":["trace[640037185] 'process raft request' (duration: 365.642325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:37.849069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.619634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-09T05:57:37.849108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:57:37.482793Z","time spent":"366.256289ms","remote":"127.0.0.1:44380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:57:37.849125Z","caller":"traceutil/trace.go:171","msg":"trace[1801889062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"241.675316ms","start":"2026-04-09T05:57:37.607433Z","end":"2026-04-09T05:57:37.849108Z","steps":["trace[1801889062] 'agreement among raft nodes before linearized reading' (duration: 241.512012ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:56.712433Z","caller":"traceutil/trace.go:171","msg":"trace[411090903] linearizableReadLoop","detail":"{readStateIndex:2193; appliedIndex:2192; }","duration":"139.209843ms","start":"2026-04-09T05:59:56.573204Z","end":"2026-04-09T05:59:56.712414Z","steps":["trace[411090903] 'read index received' (duration: 139.185322ms)","trace[411090903] 'applied index is now lower than readState.Index' (duration: 23.561µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:59:56.712498Z","caller":"traceutil/trace.go:171","msg":"trace[1053486448] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"141.510352ms","start":"2026-04-09T05:59:56.57097Z","end":"2026-04-09T05:59:56.71248Z","steps":["trace[1053486448] 'process raft request' (duration: 141.330759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:56.712597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.381546ms","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-04-09T05:59:56.712633Z","caller":"traceutil/trace.go:171","msg":"trace[531752980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2111; }","duration":"139.459548ms","start":"2026-04-09T05:59:56.573163Z","end":"2026-04-09T05:59:56.712623Z","steps":["trace[531752980] 'agreement among raft nodes before linearized reading' (duration: 139.352616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:56.986789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.883542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:59:56.987078Z","caller":"traceutil/trace.go:171","msg":"trace[1575456726] linearizableReadLoop","detail":"{readStateIndex:2195; appliedIndex:2193; }","duration":"274.527375ms","start":"2026-04-09T05:59:56.71253Z","end":"2026-04-09T05:59:56.987058Z","steps":["trace[1575456726] 'read index received' (duration: 131.389828ms)","trace[1575456726] 'applied index is now lower than readState.Index' (duration: 143.136307ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:59:56.987136Z","caller":"traceutil/trace.go:171","msg":"trace[2002087784] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"330.914255ms","start":"2026-04-09T05:59:56.656199Z","end":"2026-04-09T05:59:56.987113Z","steps":["trace[2002087784] 'process raft request' (duration: 187.612974ms)","trace[2002087784] 'compare' (duration: 142.754549ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:59:56.98719Z","caller":"traceutil/trace.go:171","msg":"trace[1168859518] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"278.558119ms","start":"2026-04-09T05:59:56.708617Z","end":"2026-04-09T05:59:56.987175Z","steps":["trace[1168859518] 'process raft request' (duration: 278.279963ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:56.987355Z","caller":"traceutil/trace.go:171","msg":"trace[1863433116] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"271.154872ms","start":"2026-04-09T05:59:56.716172Z","end":"2026-04-09T05:59:56.987326Z","steps":["trace[1863433116] 'process raft request' (duration: 270.842505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:56.987501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.022538ms","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-04-09T05:59:56.987564Z","caller":"traceutil/trace.go:171","msg":"trace[1205805868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2114; }","duration":"409.098619ms","start":"2026-04-09T05:59:56.578448Z","end":"2026-04-09T05:59:56.987547Z","steps":["trace[1205805868] 'agreement among raft nodes before linearized reading' (duration: 408.894245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:56.987568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.222144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"warn","ts":"2026-04-09T05:59:56.987599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.158126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-09T05:59:56.987656Z","caller":"traceutil/trace.go:171","msg":"trace[917704362] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2114; }","duration":"239.349726ms","start":"2026-04-09T05:59:56.748287Z","end":"2026-04-09T05:59:56.987637Z","steps":["trace[917704362] 'agreement among raft nodes before linearized reading' (duration: 239.08067ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:56.987683Z","caller":"traceutil/trace.go:171","msg":"trace[316031606] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2114; }","duration":"196.279939ms","start":"2026-04-09T05:59:56.791374Z","end":"2026-04-09T05:59:56.987654Z","steps":["trace[316031606] 'agreement among raft nodes before linearized reading' (duration: 196.013093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:56.987598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:56.578442Z","time spent":"409.14869ms","remote":"127.0.0.1:44566","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":"warn","ts":"2026-04-09T05:59:56.987747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:56.656183Z","time spent":"331.227592ms","remote":"127.0.0.1:44566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:59:56.988146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.980268ms","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-04-09T05:59:56.988196Z","caller":"traceutil/trace.go:171","msg":"trace[286844858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2114; }","duration":"410.05299ms","start":"2026-04-09T05:59:56.57813Z","end":"2026-04-09T05:59:56.988183Z","steps":["trace[286844858] 'agreement among raft nodes before linearized reading' (duration: 409.916737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:56.988249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:56.578119Z","time spent":"410.123371ms","remote":"127.0.0.1:44566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T06:00:28.729699Z","caller":"traceutil/trace.go:171","msg":"trace[540930680] transaction","detail":"{read_only:false; response_revision:2337; number_of_response:1; }","duration":"127.846082ms","start":"2026-04-09T06:00:28.601819Z","end":"2026-04-09T06:00:28.729665Z","steps":["trace[540930680] 'process raft request' (duration: 127.589436ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:58.956815Z","caller":"traceutil/trace.go:171","msg":"trace[1984912819] transaction","detail":"{read_only:false; response_revision:2505; number_of_response:1; }","duration":"125.922616ms","start":"2026-04-09T06:00:58.83086Z","end":"2026-04-09T06:00:58.956782Z","steps":["trace[1984912819] 'process raft request' (duration: 125.463556ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:04.487384Z","caller":"traceutil/trace.go:171","msg":"trace[635220374] transaction","detail":"{read_only:false; response_revision:2532; number_of_response:1; }","duration":"121.094082ms","start":"2026-04-09T06:01:04.36625Z","end":"2026-04-09T06:01:04.487344Z","steps":["trace[635220374] 'process raft request' (duration: 120.769815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:03:33.863293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.059562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-09T06:03:33.863381Z","caller":"traceutil/trace.go:171","msg":"trace[939882374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3506; }","duration":"202.184435ms","start":"2026-04-09T06:03:33.66118Z","end":"2026-04-09T06:03:33.863364Z","steps":["trace[939882374] 'range keys from in-memory index tree' (duration: 201.900089ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:03:33.863458Z","caller":"traceutil/trace.go:171","msg":"trace[1681184464] transaction","detail":"{read_only:false; response_revision:3507; number_of_response:1; }","duration":"166.928143ms","start":"2026-04-09T06:03:33.696502Z","end":"2026-04-09T06:03:33.863431Z","steps":["trace[1681184464] 'process raft request' (duration: 74.725071ms)","trace[1681184464] 'compare' (duration: 92.024299ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:05:07.260924Z","caller":"traceutil/trace.go:171","msg":"trace[1280065912] transaction","detail":"{read_only:false; response_revision:4998; number_of_response:1; }","duration":"149.959535ms","start":"2026-04-09T06:05:07.110929Z","end":"2026-04-09T06:05:07.260889Z","steps":["trace[1280065912] 'process raft request' (duration: 92.811823ms)","trace[1280065912] 'compare' (duration: 56.874646ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:05:07.621937Z","caller":"traceutil/trace.go:171","msg":"trace[13421673] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"168.330411ms","start":"2026-04-09T06:05:07.453567Z","end":"2026-04-09T06:05:07.621898Z","steps":["trace[13421673] 'process raft request' (duration: 113.501698ms)","trace[13421673] 'compare' (duration: 54.575648ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:07:01.866467Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2837} {"level":"info","ts":"2026-04-09T06:07:01.936264Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2837,"took":"68.26388ms","hash":2337883040,"current-db-size-bytes":59113472,"current-db-size":"59 MB","current-db-size-in-use-bytes":33894400,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-09T06:07:01.936342Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2337883040,"revision":2837,"compact-revision":-1} {"level":"info","ts":"2026-04-09T06:10:54.12341Z","caller":"traceutil/trace.go:171","msg":"trace[1626159997] linearizableReadLoop","detail":"{readStateIndex:8038; appliedIndex:8037; }","duration":"183.259308ms","start":"2026-04-09T06:10:53.940135Z","end":"2026-04-09T06:10:54.123394Z","steps":["trace[1626159997] 'read index received' (duration: 183.116285ms)","trace[1626159997] 'applied index is now lower than readState.Index' (duration: 142.263µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:10:54.123567Z","caller":"traceutil/trace.go:171","msg":"trace[1238646333] transaction","detail":"{read_only:false; response_revision:7765; number_of_response:1; }","duration":"225.520718ms","start":"2026-04-09T06:10:53.898036Z","end":"2026-04-09T06:10:54.123557Z","steps":["trace[1238646333] 'process raft request' (duration: 225.216331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:10:54.123767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.031723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-09T06:10:54.123845Z","caller":"traceutil/trace.go:171","msg":"trace[966444908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7765; }","duration":"167.131645ms","start":"2026-04-09T06:10:53.956696Z","end":"2026-04-09T06:10:54.123827Z","steps":["trace[966444908] 'agreement among raft nodes before linearized reading' (duration: 166.931831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:10:54.123901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.150923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hvr4h\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-09T06:10:54.123939Z","caller":"traceutil/trace.go:171","msg":"trace[1470233371] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hvr4h; range_end:; response_count:1; response_revision:7765; }","duration":"165.241455ms","start":"2026-04-09T06:10:53.958691Z","end":"2026-04-09T06:10:54.123933Z","steps":["trace[1470233371] 'agreement among raft nodes before linearized reading' (duration: 165.079691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:10:54.124014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.884163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-09T06:10:54.124052Z","caller":"traceutil/trace.go:171","msg":"trace[354246628] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7765; }","duration":"183.959604ms","start":"2026-04-09T06:10:53.940081Z","end":"2026-04-09T06:10:54.124041Z","steps":["trace[354246628] 'agreement among raft nodes before linearized reading' (duration: 183.872622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:10:54.124117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.91167ms","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-04-09T06:10:54.124208Z","caller":"traceutil/trace.go:171","msg":"trace[1071472701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7765; }","duration":"166.056594ms","start":"2026-04-09T06:10:53.958131Z","end":"2026-04-09T06:10:54.124188Z","steps":["trace[1071472701] 'agreement among raft nodes before linearized reading' (duration: 165.776838ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:11:05.460279Z","caller":"traceutil/trace.go:171","msg":"trace[613655902] transaction","detail":"{read_only:false; response_revision:7825; number_of_response:1; }","duration":"116.087069ms","start":"2026-04-09T06:11:05.344164Z","end":"2026-04-09T06:11:05.460251Z","steps":["trace[613655902] 'process raft request' (duration: 103.786968ms)","trace[613655902] 'compare' (duration: 12.162528ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:11:33.346376Z","caller":"traceutil/trace.go:171","msg":"trace[948879674] transaction","detail":"{read_only:false; response_revision:8025; number_of_response:1; }","duration":"102.748626ms","start":"2026-04-09T06:11:33.243611Z","end":"2026-04-09T06:11:33.34636Z","steps":["trace[948879674] 'process raft request' (duration: 102.635534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:11:45.962889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.112393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-09T06:11:45.962959Z","caller":"traceutil/trace.go:171","msg":"trace[820582910] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8137; }","duration":"144.253787ms","start":"2026-04-09T06:11:45.818686Z","end":"2026-04-09T06:11:45.96294Z","steps":["trace[820582910] 'range keys from in-memory index tree' (duration: 143.916609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:11:45.962964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.875281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-v8q9w\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-04-09T06:11:45.96305Z","caller":"traceutil/trace.go:171","msg":"trace[1996690246] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-v8q9w; range_end:; response_count:1; response_revision:8137; }","duration":"129.013504ms","start":"2026-04-09T06:11:45.834018Z","end":"2026-04-09T06:11:45.963032Z","steps":["trace[1996690246] 'range keys from in-memory index tree' (duration: 128.723388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:11:45.963122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.799167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-04-09T06:11:45.963274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.346529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-04-09T06:11:45.963316Z","caller":"traceutil/trace.go:171","msg":"trace[1352396750] transaction","detail":"{read_only:false; response_revision:8138; number_of_response:1; }","duration":"128.83841ms","start":"2026-04-09T06:11:45.83444Z","end":"2026-04-09T06:11:45.963279Z","steps":["trace[1352396750] 'process raft request' (duration: 26.769107ms)","trace[1352396750] 'compare' (duration: 101.51674ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:11:45.963354Z","caller":"traceutil/trace.go:171","msg":"trace[1533396393] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8137; }","duration":"178.486553ms","start":"2026-04-09T06:11:45.784848Z","end":"2026-04-09T06:11:45.963335Z","steps":["trace[1533396393] 'range keys from in-memory index tree' (duration: 177.720486ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:11:47.630247Z","caller":"traceutil/trace.go:171","msg":"trace[643477483] linearizableReadLoop","detail":"{readStateIndex:8438; appliedIndex:8437; }","duration":"129.512215ms","start":"2026-04-09T06:11:47.500712Z","end":"2026-04-09T06:11:47.630224Z","steps":["trace[643477483] 'read index received' (duration: 129.397372ms)","trace[643477483] 'applied index is now lower than readState.Index' (duration: 113.753µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:11:47.630305Z","caller":"traceutil/trace.go:171","msg":"trace[162292829] transaction","detail":"{read_only:false; response_revision:8149; number_of_response:1; }","duration":"147.19031ms","start":"2026-04-09T06:11:47.483083Z","end":"2026-04-09T06:11:47.630273Z","steps":["trace[162292829] 'process raft request' (duration: 147.010306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:11:47.631348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.615278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2366191"} {"level":"info","ts":"2026-04-09T06:11:47.631442Z","caller":"traceutil/trace.go:171","msg":"trace[1678987908] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8149; }","duration":"130.754451ms","start":"2026-04-09T06:11:47.500668Z","end":"2026-04-09T06:11:47.631422Z","steps":["trace[1678987908] 'agreement among raft nodes before linearized reading' (duration: 129.651158ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:11:47.644478Z","caller":"traceutil/trace.go:171","msg":"trace[1671373695] transaction","detail":"{read_only:false; response_revision:8150; number_of_response:1; }","duration":"132.000098ms","start":"2026-04-09T06:11:47.51246Z","end":"2026-04-09T06:11:47.64446Z","steps":["trace[1671373695] 'process raft request' (duration: 131.896995ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:11:47.928251Z","caller":"traceutil/trace.go:171","msg":"trace[1461703366] transaction","detail":"{read_only:false; response_revision:8152; number_of_response:1; }","duration":"100.778095ms","start":"2026-04-09T06:11:47.827453Z","end":"2026-04-09T06:11:47.928231Z","steps":["trace[1461703366] 'process raft request' (duration: 100.639102ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:12:01.872592Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6002} {"level":"info","ts":"2026-04-09T06:12:01.962624Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6002,"took":"88.514306ms","hash":2246596843,"current-db-size-bytes":59113472,"current-db-size":"59 MB","current-db-size-in-use-bytes":23019520,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-09T06:12:01.962692Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2246596843,"revision":6002,"compact-revision":2837} {"level":"info","ts":"2026-04-09T06:12:52.535622Z","caller":"traceutil/trace.go:171","msg":"trace[1480700349] transaction","detail":"{read_only:false; response_revision:8767; number_of_response:1; }","duration":"210.01814ms","start":"2026-04-09T06:12:52.325579Z","end":"2026-04-09T06:12:52.535597Z","steps":["trace[1480700349] 'process raft request' (duration: 209.864377ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:12:52.535644Z","caller":"traceutil/trace.go:171","msg":"trace[482950387] linearizableReadLoop","detail":"{readStateIndex:9074; appliedIndex:9074; }","duration":"167.05806ms","start":"2026-04-09T06:12:52.368556Z","end":"2026-04-09T06:12:52.535614Z","steps":["trace[482950387] 'read index received' (duration: 167.04893ms)","trace[482950387] 'applied index is now lower than readState.Index' (duration: 7.09µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:12:52.536108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.489449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-04-09T06:12:52.536173Z","caller":"traceutil/trace.go:171","msg":"trace[1767346485] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8767; }","duration":"167.634843ms","start":"2026-04-09T06:12:52.368522Z","end":"2026-04-09T06:12:52.536157Z","steps":["trace[1767346485] 'agreement among raft nodes before linearized reading' (duration: 167.177473ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:12:56.161377Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000238d.wal"} {"level":"info","ts":"2026-04-09T06:14:43.334407Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-09T06:14:43.339583Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-09T06:14:43.339684Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-09T06:17:01.879498Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8347} {"level":"info","ts":"2026-04-09T06:17:01.956289Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8347,"took":"74.628932ms","hash":3195946372,"current-db-size-bytes":59113472,"current-db-size":"59 MB","current-db-size-in-use-bytes":24285184,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-09T06:17:01.956352Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3195946372,"revision":8347,"compact-revision":6002} {"level":"info","ts":"2026-04-09T06:22:01.885799Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10563} {"level":"info","ts":"2026-04-09T06:22:01.958365Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10563,"took":"70.735106ms","hash":1162068658,"current-db-size-bytes":59113472,"current-db-size":"59 MB","current-db-size-in-use-bytes":19312640,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-04-09T06:22:01.958415Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1162068658,"revision":10563,"compact-revision":8347}