{"level":"warn","ts":"2026-04-07T15:27:07.542639Z","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-07T15:27:07.542771Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144: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.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-07T15:27:07.542861Z","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-07T15:27:07.542875Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-04-07T15:27:07.542894Z","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-07T15:27:07.544026Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-04-07T15:27:07.544152Z","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.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-07T15:27:07.548041Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.543996ms"} {"level":"info","ts":"2026-04-07T15:27:07.554002Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-04-07T15:27:07.554081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T15:27:07.554119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-04-07T15:27:07.554133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T15:27:07.554145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-04-07T15:27:07.554204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-04-07T15:27:07.56429Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T15:27:07.566128Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T15:27:07.567472Z","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-07T15:27:07.569831Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T15:27:07.57014Z","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-07T15:27:07.570132Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T15:27:07.570322Z","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-07T15:27:07.570347Z","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-07T15:27:07.572622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-04-07T15:27:07.57337Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-04-07T15:27:07.575441Z","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-07T15:27:07.575558Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-04-07T15:27:07.575747Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-04-07T15:27:07.575849Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T15:27:07.575895Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T15:27:08.556317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T15:27:08.556408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T15:27:08.556428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-04-07T15:27:08.556448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-04-07T15:27:08.556457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-04-07T15:27:08.556471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-04-07T15:27:08.556504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-04-07T15:27:08.558814Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:27:08.559876Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:27:08.559878Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T15:27:08.559947Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:27:08.560149Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T15:27:08.56019Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T15:27:08.560445Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:27:08.560518Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:27:08.560547Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:27:08.561709Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T15:27:08.562149Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-04-07T15:27:35.045294Z","caller":"traceutil/trace.go:171","msg":"trace[396195734] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"198.098487ms","start":"2026-04-07T15:27:34.847159Z","end":"2026-04-07T15:27:35.045257Z","steps":["trace[396195734] 'process raft request' (duration: 197.901831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:27:39.661338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.187416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-04-07T15:27:39.661484Z","caller":"traceutil/trace.go:171","msg":"trace[1286609174] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:586; }","duration":"212.396493ms","start":"2026-04-07T15:27:39.449062Z","end":"2026-04-07T15:27:39.661459Z","steps":["trace[1286609174] 'range keys from in-memory index tree' (duration: 211.925719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:27:44.717107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.415144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:27:44.717288Z","caller":"traceutil/trace.go:171","msg":"trace[878618544] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"203.590911ms","start":"2026-04-07T15:27:44.51364Z","end":"2026-04-07T15:27:44.717231Z","steps":["trace[878618544] 'process raft request' (duration: 99.326622ms)","trace[878618544] 'compare' (duration: 102.96849ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:27:45.255544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.233016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:27:45.255646Z","caller":"traceutil/trace.go:171","msg":"trace[68521323] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"162.666204ms","start":"2026-04-07T15:27:45.092963Z","end":"2026-04-07T15:27:45.255629Z","steps":["trace[68521323] 'read index received' (duration: 89.632µs)","trace[68521323] 'applied index is now lower than readState.Index' (duration: 162.575512ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:27:45.255728Z","caller":"traceutil/trace.go:171","msg":"trace[1714844658] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"473.938665ms","start":"2026-04-07T15:27:44.781748Z","end":"2026-04-07T15:27:45.255687Z","steps":["trace[1714844658] 'process raft request' (duration: 42.498592ms)","trace[1714844658] 'compare' (duration: 430.696451ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:27:45.255883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:27:44.781719Z","time spent":"474.094439ms","remote":"127.0.0.1:48156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:27:45.256009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.033765ms","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-07T15:27:45.256102Z","caller":"traceutil/trace.go:171","msg":"trace[1296438948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"163.164649ms","start":"2026-04-07T15:27:45.092907Z","end":"2026-04-07T15:27:45.256071Z","steps":["trace[1296438948] 'agreement among raft nodes before linearized reading' (duration: 162.889991ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:28:00.96558Z","caller":"traceutil/trace.go:171","msg":"trace[238157119] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"143.347768ms","start":"2026-04-07T15:28:00.822205Z","end":"2026-04-07T15:28:00.965553Z","steps":["trace[238157119] 'process raft request' (duration: 143.194304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:28:18.781728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.684475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T15:28:18.781822Z","caller":"traceutil/trace.go:171","msg":"trace[1999969755] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1098; }","duration":"120.803389ms","start":"2026-04-07T15:28:18.661Z","end":"2026-04-07T15:28:18.781803Z","steps":["trace[1999969755] 'range keys from in-memory index tree' (duration: 120.426208ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:28:28.840287Z","caller":"traceutil/trace.go:171","msg":"trace[307417656] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"158.701479ms","start":"2026-04-07T15:28:28.681562Z","end":"2026-04-07T15:28:28.840264Z","steps":["trace[307417656] 'process raft request' (duration: 158.536254ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:29:16.923258Z","caller":"traceutil/trace.go:171","msg":"trace[87851691] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"199.343697ms","start":"2026-04-07T15:29:16.723889Z","end":"2026-04-07T15:29:16.923232Z","steps":["trace[87851691] 'read index received' (duration: 180.125306ms)","trace[87851691] 'applied index is now lower than readState.Index' (duration: 19.216751ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:29:16.923851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.964894ms","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-04-07T15:29:16.923962Z","caller":"traceutil/trace.go:171","msg":"trace[678923932] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1856; }","duration":"200.18558ms","start":"2026-04-07T15:29:16.72376Z","end":"2026-04-07T15:29:16.923945Z","steps":["trace[678923932] 'agreement among raft nodes before linearized reading' (duration: 199.599323ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:29:29.233458Z","caller":"traceutil/trace.go:171","msg":"trace[478087845] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"177.375837ms","start":"2026-04-07T15:29:29.05605Z","end":"2026-04-07T15:29:29.233426Z","steps":["trace[478087845] 'process raft request' (duration: 143.609669ms)","trace[478087845] 'compare' (duration: 33.625794ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:30:06.124109Z","caller":"traceutil/trace.go:171","msg":"trace[1950766127] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"148.158466ms","start":"2026-04-07T15:30:05.975914Z","end":"2026-04-07T15:30:06.124073Z","steps":["trace[1950766127] 'process raft request' (duration: 133.506093ms)","trace[1950766127] 'compare' (duration: 14.427587ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:30:11.575335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.972675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-07T15:30:11.575381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.830243ms","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-04-07T15:30:11.575431Z","caller":"traceutil/trace.go:171","msg":"trace[159949901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2203; }","duration":"124.087768ms","start":"2026-04-07T15:30:11.451324Z","end":"2026-04-07T15:30:11.575412Z","steps":["trace[159949901] 'range keys from in-memory index tree' (duration: 123.744458ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:30:11.575469Z","caller":"traceutil/trace.go:171","msg":"trace[339681645] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2203; }","duration":"105.965217ms","start":"2026-04-07T15:30:11.469476Z","end":"2026-04-07T15:30:11.575441Z","steps":["trace[339681645] 'range keys from in-memory index tree' (duration: 105.662699ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:30:12.141874Z","caller":"traceutil/trace.go:171","msg":"trace[213024315] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"128.363337ms","start":"2026-04-07T15:30:12.013486Z","end":"2026-04-07T15:30:12.141849Z","steps":["trace[213024315] 'process raft request' (duration: 128.215833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:30:12.411129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.289535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:30:12.411512Z","caller":"traceutil/trace.go:171","msg":"trace[1931425529] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"258.178528ms","start":"2026-04-07T15:30:12.153297Z","end":"2026-04-07T15:30:12.411475Z","steps":["trace[1931425529] 'process raft request' (duration: 123.46702ms)","trace[1931425529] 'compare' (duration: 134.193193ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:30:12.447729Z","caller":"traceutil/trace.go:171","msg":"trace[530324417] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"109.150177ms","start":"2026-04-07T15:30:12.338551Z","end":"2026-04-07T15:30:12.447702Z","steps":["trace[530324417] 'process raft request' (duration: 108.95666ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:31:28.937283Z","caller":"traceutil/trace.go:171","msg":"trace[1467694848] transaction","detail":"{read_only:false; response_revision:2816; number_of_response:1; }","duration":"171.347522ms","start":"2026-04-07T15:31:28.765759Z","end":"2026-04-07T15:31:28.937106Z","steps":["trace[1467694848] 'process raft request' (duration: 171.166066ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:31:28.938811Z","caller":"traceutil/trace.go:171","msg":"trace[1186753592] linearizableReadLoop","detail":"{readStateIndex:2926; appliedIndex:2923; }","duration":"120.34323ms","start":"2026-04-07T15:31:28.818439Z","end":"2026-04-07T15:31:28.938782Z","steps":["trace[1186753592] 'read index received' (duration: 118.478718ms)","trace[1186753592] 'applied index is now lower than readState.Index' (duration: 1.863122ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:31:28.938899Z","caller":"traceutil/trace.go:171","msg":"trace[1204139057] transaction","detail":"{read_only:false; response_revision:2817; number_of_response:1; }","duration":"171.048653ms","start":"2026-04-07T15:31:28.767821Z","end":"2026-04-07T15:31:28.93887Z","steps":["trace[1204139057] 'process raft request' (duration: 170.768075ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:31:28.938911Z","caller":"traceutil/trace.go:171","msg":"trace[991954773] transaction","detail":"{read_only:false; response_revision:2818; number_of_response:1; }","duration":"132.069941ms","start":"2026-04-07T15:31:28.806815Z","end":"2026-04-07T15:31:28.938885Z","steps":["trace[991954773] 'process raft request' (duration: 131.917857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:31:28.939013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.562676ms","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-07T15:31:28.939048Z","caller":"traceutil/trace.go:171","msg":"trace[1407748134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2818; }","duration":"120.648038ms","start":"2026-04-07T15:31:28.81839Z","end":"2026-04-07T15:31:28.939038Z","steps":["trace[1407748134] 'agreement among raft nodes before linearized reading' (duration: 120.493184ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:34:10.197716Z","caller":"traceutil/trace.go:171","msg":"trace[278077321] transaction","detail":"{read_only:false; response_revision:3872; number_of_response:1; }","duration":"111.013876ms","start":"2026-04-07T15:34:10.086678Z","end":"2026-04-07T15:34:10.197692Z","steps":["trace[278077321] 'process raft request' (duration: 110.861942ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:35:18.122998Z","caller":"traceutil/trace.go:171","msg":"trace[781735671] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"103.851021ms","start":"2026-04-07T15:35:18.019109Z","end":"2026-04-07T15:35:18.12296Z","steps":["trace[781735671] 'process raft request' (duration: 79.925394ms)","trace[781735671] 'compare' (duration: 23.812354ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:35:18.135306Z","caller":"traceutil/trace.go:171","msg":"trace[1153475119] linearizableReadLoop","detail":"{readStateIndex:4872; appliedIndex:4870; }","duration":"112.075624ms","start":"2026-04-07T15:35:18.023186Z","end":"2026-04-07T15:35:18.135262Z","steps":["trace[1153475119] 'read index received' (duration: 75.859698ms)","trace[1153475119] 'applied index is now lower than readState.Index' (duration: 36.214816ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:35:18.135474Z","caller":"traceutil/trace.go:171","msg":"trace[687907847] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"115.064759ms","start":"2026-04-07T15:35:18.020398Z","end":"2026-04-07T15:35:18.135463Z","steps":["trace[687907847] 'process raft request' (duration: 114.618935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:35:18.135667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.000049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:35:18.135754Z","caller":"traceutil/trace.go:171","msg":"trace[399879743] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:0; response_revision:4691; }","duration":"108.138102ms","start":"2026-04-07T15:35:18.027595Z","end":"2026-04-07T15:35:18.135733Z","steps":["trace[399879743] 'agreement among raft nodes before linearized reading' (duration: 107.973387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:35:18.137372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.155492ms","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-07T15:35:18.137467Z","caller":"traceutil/trace.go:171","msg":"trace[950390773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4691; }","duration":"114.297796ms","start":"2026-04-07T15:35:18.023149Z","end":"2026-04-07T15:35:18.137447Z","steps":["trace[950390773] 'agreement among raft nodes before linearized reading' (duration: 112.508166ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:35:35.313372Z","caller":"traceutil/trace.go:171","msg":"trace[805682363] transaction","detail":"{read_only:false; response_revision:5140; number_of_response:1; }","duration":"100.971713ms","start":"2026-04-07T15:35:35.212364Z","end":"2026-04-07T15:35:35.313335Z","steps":["trace[805682363] 'process raft request' (duration: 100.624303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:36:01.67048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.579821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:36:01.67104Z","caller":"traceutil/trace.go:171","msg":"trace[2108992596] transaction","detail":"{read_only:false; response_revision:5551; number_of_response:1; }","duration":"157.046307ms","start":"2026-04-07T15:36:01.513958Z","end":"2026-04-07T15:36:01.671004Z","steps":["trace[2108992596] 'process raft request' (duration: 55.880219ms)","trace[2108992596] 'compare' (duration: 100.502449ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:36:01.671176Z","caller":"traceutil/trace.go:171","msg":"trace[594166349] transaction","detail":"{read_only:false; response_revision:5552; number_of_response:1; }","duration":"155.670647ms","start":"2026-04-07T15:36:01.515487Z","end":"2026-04-07T15:36:01.671158Z","steps":["trace[594166349] 'process raft request' (duration: 155.080961ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:36:02.511874Z","caller":"traceutil/trace.go:171","msg":"trace[323711794] transaction","detail":"{read_only:false; response_revision:5563; number_of_response:1; }","duration":"136.572127ms","start":"2026-04-07T15:36:02.375255Z","end":"2026-04-07T15:36:02.511827Z","steps":["trace[323711794] 'process raft request' (duration: 136.179866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:36:02.718381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.01844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bjkgx\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T15:36:02.718495Z","caller":"traceutil/trace.go:171","msg":"trace[1429528408] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bjkgx; range_end:; response_count:1; response_revision:5563; }","duration":"103.183095ms","start":"2026-04-07T15:36:02.61529Z","end":"2026-04-07T15:36:02.718473Z","steps":["trace[1429528408] 'range keys from in-memory index tree' (duration: 102.777473ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:36:02.745116Z","caller":"traceutil/trace.go:171","msg":"trace[1020579906] transaction","detail":"{read_only:false; response_revision:5564; number_of_response:1; }","duration":"128.022536ms","start":"2026-04-07T15:36:02.617056Z","end":"2026-04-07T15:36:02.745078Z","steps":["trace[1020579906] 'process raft request' (duration: 127.79446ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:36:02.775064Z","caller":"traceutil/trace.go:171","msg":"trace[452958946] transaction","detail":"{read_only:false; response_revision:5565; number_of_response:1; }","duration":"126.413241ms","start":"2026-04-07T15:36:02.648623Z","end":"2026-04-07T15:36:02.775037Z","steps":["trace[452958946] 'process raft request' (duration: 126.165874ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:37:08.58275Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3035} {"level":"info","ts":"2026-04-07T15:37:08.667795Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3035,"took":"83.433738ms","hash":2900658875,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":31375360,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-07T15:37:08.667852Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2900658875,"revision":3035,"compact-revision":-1} {"level":"info","ts":"2026-04-07T15:42:02.280646Z","caller":"traceutil/trace.go:171","msg":"trace[716145942] transaction","detail":"{read_only:false; response_revision:8286; number_of_response:1; }","duration":"155.28311ms","start":"2026-04-07T15:42:02.125336Z","end":"2026-04-07T15:42:02.280619Z","steps":["trace[716145942] 'process raft request' (duration: 155.088974ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:42:02.280681Z","caller":"traceutil/trace.go:171","msg":"trace[1761678094] linearizableReadLoop","detail":"{readStateIndex:8578; appliedIndex:8578; }","duration":"148.819987ms","start":"2026-04-07T15:42:02.131831Z","end":"2026-04-07T15:42:02.280651Z","steps":["trace[1761678094] 'read index received' (duration: 148.809737ms)","trace[1761678094] 'applied index is now lower than readState.Index' (duration: 8.05µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:42:02.280917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.070744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:42:02.280955Z","caller":"traceutil/trace.go:171","msg":"trace[1856397109] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:8286; }","duration":"149.183359ms","start":"2026-04-07T15:42:02.131758Z","end":"2026-04-07T15:42:02.280942Z","steps":["trace[1856397109] 'agreement among raft nodes before linearized reading' (duration: 148.998963ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:42:02.424856Z","caller":"traceutil/trace.go:171","msg":"trace[1000973649] transaction","detail":"{read_only:false; response_revision:8287; number_of_response:1; }","duration":"292.981173ms","start":"2026-04-07T15:42:02.131851Z","end":"2026-04-07T15:42:02.424832Z","steps":["trace[1000973649] 'process raft request' (duration: 292.482579ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:42:02.425155Z","caller":"traceutil/trace.go:171","msg":"trace[1162383877] transaction","detail":"{read_only:false; response_revision:8288; number_of_response:1; }","duration":"195.584614ms","start":"2026-04-07T15:42:02.229496Z","end":"2026-04-07T15:42:02.425081Z","steps":["trace[1162383877] 'process raft request' (duration: 195.047999ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:42:08.589444Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6091} {"level":"info","ts":"2026-04-07T15:42:08.682184Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6091,"took":"90.846326ms","hash":3384894430,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":23760896,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-07T15:42:08.682282Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3384894430,"revision":6091,"compact-revision":3035} {"level":"warn","ts":"2026-04-07T15:42:13.916127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.958594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-07T15:42:13.916241Z","caller":"traceutil/trace.go:171","msg":"trace[1675825123] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8347; }","duration":"126.117549ms","start":"2026-04-07T15:42:13.790104Z","end":"2026-04-07T15:42:13.916221Z","steps":["trace[1675825123] 'range keys from in-memory index tree' (duration: 125.791949ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:42:14.138034Z","caller":"traceutil/trace.go:171","msg":"trace[1150747153] linearizableReadLoop","detail":"{readStateIndex:8643; appliedIndex:8642; }","duration":"158.775037ms","start":"2026-04-07T15:42:13.979237Z","end":"2026-04-07T15:42:14.138012Z","steps":["trace[1150747153] 'read index received' (duration: 158.755487ms)","trace[1150747153] 'applied index is now lower than readState.Index' (duration: 18.75µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:42:14.138137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.89625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:42:14.138161Z","caller":"traceutil/trace.go:171","msg":"trace[756864856] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8348; }","duration":"158.982992ms","start":"2026-04-07T15:42:13.979171Z","end":"2026-04-07T15:42:14.138154Z","steps":["trace[756864856] 'agreement among raft nodes before linearized reading' (duration: 158.924491ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:42:14.138142Z","caller":"traceutil/trace.go:171","msg":"trace[1489358528] transaction","detail":"{read_only:false; response_revision:8348; number_of_response:1; }","duration":"177.080722ms","start":"2026-04-07T15:42:13.960991Z","end":"2026-04-07T15:42:14.138071Z","steps":["trace[1489358528] 'process raft request' (duration: 176.891457ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:42:15.792572Z","caller":"traceutil/trace.go:171","msg":"trace[1301436268] linearizableReadLoop","detail":"{readStateIndex:8649; appliedIndex:8648; }","duration":"154.754534ms","start":"2026-04-07T15:42:15.637793Z","end":"2026-04-07T15:42:15.792548Z","steps":["trace[1301436268] 'read index received' (duration: 154.61501ms)","trace[1301436268] 'applied index is now lower than readState.Index' (duration: 138.404µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:42:15.792698Z","caller":"traceutil/trace.go:171","msg":"trace[1402992802] transaction","detail":"{read_only:false; response_revision:8354; number_of_response:1; }","duration":"164.426087ms","start":"2026-04-07T15:42:15.628241Z","end":"2026-04-07T15:42:15.792667Z","steps":["trace[1402992802] 'process raft request' (duration: 164.142159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:42:15.792933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.357184ms","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":"info","ts":"2026-04-07T15:42:15.793001Z","caller":"traceutil/trace.go:171","msg":"trace[158476431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8354; }","duration":"111.488467ms","start":"2026-04-07T15:42:15.681497Z","end":"2026-04-07T15:42:15.792986Z","steps":["trace[158476431] 'agreement among raft nodes before linearized reading' (duration: 111.23674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:42:15.793143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.32038ms","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-07T15:42:15.793217Z","caller":"traceutil/trace.go:171","msg":"trace[1938045126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8354; }","duration":"155.504995ms","start":"2026-04-07T15:42:15.637698Z","end":"2026-04-07T15:42:15.793203Z","steps":["trace[1938045126] 'agreement among raft nodes before linearized reading' (duration: 154.994471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:42:15.947598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.638937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:42:15.9478Z","caller":"traceutil/trace.go:171","msg":"trace[434386106] transaction","detail":"{read_only:false; response_revision:8356; number_of_response:1; }","duration":"150.000601ms","start":"2026-04-07T15:42:15.797781Z","end":"2026-04-07T15:42:15.947781Z","steps":["trace[434386106] 'process raft request' (duration: 149.932489ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:42:15.947955Z","caller":"traceutil/trace.go:171","msg":"trace[556798214] transaction","detail":"{read_only:false; response_revision:8355; number_of_response:1; }","duration":"151.464662ms","start":"2026-04-07T15:42:15.796455Z","end":"2026-04-07T15:42:15.947919Z","steps":["trace[556798214] 'process raft request' (duration: 37.345112ms)","trace[556798214] 'compare' (duration: 113.528684ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:42:43.768287Z","caller":"traceutil/trace.go:171","msg":"trace[939978887] transaction","detail":"{read_only:false; response_revision:8558; number_of_response:1; }","duration":"319.960973ms","start":"2026-04-07T15:42:43.448302Z","end":"2026-04-07T15:42:43.768263Z","steps":["trace[939978887] 'process raft request' (duration: 319.778088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:42:43.768446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:42:43.448284Z","time spent":"320.083287ms","remote":"127.0.0.1:48186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:42:43.862269Z","caller":"traceutil/trace.go:171","msg":"trace[1508325823] transaction","detail":"{read_only:false; response_revision:8560; number_of_response:1; }","duration":"352.765616ms","start":"2026-04-07T15:42:43.50948Z","end":"2026-04-07T15:42:43.862245Z","steps":["trace[1508325823] 'process raft request' (duration: 352.714445ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:42:43.86236Z","caller":"traceutil/trace.go:171","msg":"trace[779791836] transaction","detail":"{read_only:false; response_revision:8559; number_of_response:1; }","duration":"412.897949ms","start":"2026-04-07T15:42:43.449419Z","end":"2026-04-07T15:42:43.862317Z","steps":["trace[779791836] 'process raft request' (duration: 411.51745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:42:43.862411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:42:43.509456Z","time spent":"352.88514ms","remote":"127.0.0.1:48186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:42:43.862563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:42:43.449395Z","time spent":"413.054194ms","remote":"127.0.0.1:48186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:42:58.454671Z","caller":"traceutil/trace.go:171","msg":"trace[1723349035] transaction","detail":"{read_only:false; response_revision:8686; number_of_response:1; }","duration":"108.79085ms","start":"2026-04-07T15:42:58.345839Z","end":"2026-04-07T15:42:58.454629Z","steps":["trace[1723349035] 'process raft request' (duration: 108.523173ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:43:14.786675Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023c8.wal"} {"level":"info","ts":"2026-04-07T15:44:49.072463Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T15:44:49.078602Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T15:44:49.078748Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T15:47:08.596303Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8320} {"level":"info","ts":"2026-04-07T15:47:08.672441Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8320,"took":"73.872518ms","hash":2437729155,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":24854528,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-07T15:47:08.672507Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2437729155,"revision":8320,"compact-revision":6091} {"level":"info","ts":"2026-04-07T15:47:19.931714Z","caller":"traceutil/trace.go:171","msg":"trace[496240088] transaction","detail":"{read_only:false; response_revision:10597; number_of_response:1; }","duration":"322.132079ms","start":"2026-04-07T15:47:19.609551Z","end":"2026-04-07T15:47:19.931683Z","steps":["trace[496240088] 'process raft request' (duration: 321.927724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:47:19.931991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:47:19.609527Z","time spent":"322.283534ms","remote":"127.0.0.1:48186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:47:19.932502Z","caller":"traceutil/trace.go:171","msg":"trace[2080245051] transaction","detail":"{read_only:false; response_revision:10598; number_of_response:1; }","duration":"290.918493ms","start":"2026-04-07T15:47:19.641555Z","end":"2026-04-07T15:47:19.932473Z","steps":["trace[2080245051] 'process raft request' (duration: 290.748908ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:47:19.932714Z","caller":"traceutil/trace.go:171","msg":"trace[87047057] transaction","detail":"{read_only:false; response_revision:10599; number_of_response:1; }","duration":"287.939109ms","start":"2026-04-07T15:47:19.644718Z","end":"2026-04-07T15:47:19.932657Z","steps":["trace[87047057] 'process raft request' (duration: 287.714862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:48:49.27976Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440201444870294939,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T15:48:49.429591Z","caller":"traceutil/trace.go:171","msg":"trace[307425160] transaction","detail":"{read_only:false; response_revision:11211; number_of_response:1; }","duration":"651.033993ms","start":"2026-04-07T15:48:48.778527Z","end":"2026-04-07T15:48:49.429561Z","steps":["trace[307425160] 'process raft request' (duration: 650.987112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:48:49.429744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:48:48.778516Z","time spent":"651.147027ms","remote":"127.0.0.1:48186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:48:49.429867Z","caller":"traceutil/trace.go:171","msg":"trace[561248437] transaction","detail":"{read_only:false; response_revision:11210; number_of_response:1; }","duration":"651.658152ms","start":"2026-04-07T15:48:48.778196Z","end":"2026-04-07T15:48:49.429855Z","steps":["trace[561248437] 'process raft request' (duration: 651.176579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:48:49.429915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:48:48.778186Z","time spent":"651.702173ms","remote":"127.0.0.1:48186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:48:49.430692Z","caller":"traceutil/trace.go:171","msg":"trace[1134870734] linearizableReadLoop","detail":"{readStateIndex:11605; appliedIndex:11603; }","duration":"651.561978ms","start":"2026-04-07T15:48:48.779097Z","end":"2026-04-07T15:48:49.430659Z","steps":["trace[1134870734] 'read index received' (duration: 650.89075ms)","trace[1134870734] 'applied index is now lower than readState.Index' (duration: 669.928µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:48:49.430773Z","caller":"traceutil/trace.go:171","msg":"trace[1620749162] transaction","detail":"{read_only:false; response_revision:11212; number_of_response:1; }","duration":"195.747649ms","start":"2026-04-07T15:48:49.234956Z","end":"2026-04-07T15:48:49.430704Z","steps":["trace[1620749162] 'process raft request' (duration: 195.523573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:48:49.430934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.811515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:48:49.430984Z","caller":"traceutil/trace.go:171","msg":"trace[1323860703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11213; }","duration":"651.892797ms","start":"2026-04-07T15:48:48.77908Z","end":"2026-04-07T15:48:49.430972Z","steps":["trace[1323860703] 'agreement among raft nodes before linearized reading' (duration: 651.765154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:48:49.431021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:48:48.779069Z","time spent":"651.944348ms","remote":"127.0.0.1:47962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T15:48:49.430819Z","caller":"traceutil/trace.go:171","msg":"trace[1558370238] transaction","detail":"{read_only:false; response_revision:11213; number_of_response:1; }","duration":"183.563776ms","start":"2026-04-07T15:48:49.247227Z","end":"2026-04-07T15:48:49.430791Z","steps":["trace[1558370238] 'process raft request' (duration: 183.382751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:48:49.431403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.627502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:1 size:5127"} {"level":"info","ts":"2026-04-07T15:48:49.431448Z","caller":"traceutil/trace.go:171","msg":"trace[2087534010] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:1; response_revision:11213; }","duration":"380.726195ms","start":"2026-04-07T15:48:49.050707Z","end":"2026-04-07T15:48:49.431434Z","steps":["trace[2087534010] 'agreement among raft nodes before linearized reading' (duration: 380.54113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:48:49.431481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:48:49.050684Z","time spent":"380.788637ms","remote":"127.0.0.1:48156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":5150,"request content":"key:\"/registry/jobs/openstack/staffeln-db-sync\" "} {"level":"warn","ts":"2026-04-07T15:48:49.431561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.515664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/glance-api-5f669d89b8-rbtdt.18a41dc603cc8016\" ","response":"range_response_count:1 size:2106"} {"level":"info","ts":"2026-04-07T15:48:49.431613Z","caller":"traceutil/trace.go:171","msg":"trace[1852992311] range","detail":"{range_begin:/registry/events/openstack/glance-api-5f669d89b8-rbtdt.18a41dc603cc8016; range_end:; response_count:1; response_revision:11213; }","duration":"316.589226ms","start":"2026-04-07T15:48:49.115008Z","end":"2026-04-07T15:48:49.431597Z","steps":["trace[1852992311] 'agreement among raft nodes before linearized reading' (duration: 316.442482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:48:49.431645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:48:49.114998Z","time spent":"316.638848ms","remote":"127.0.0.1:48032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":2129,"request content":"key:\"/registry/events/openstack/glance-api-5f669d89b8-rbtdt.18a41dc603cc8016\" "} {"level":"warn","ts":"2026-04-07T15:48:49.431716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.170399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:48:49.43183Z","caller":"traceutil/trace.go:171","msg":"trace[1640536887] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:11213; }","duration":"339.322844ms","start":"2026-04-07T15:48:49.092476Z","end":"2026-04-07T15:48:49.431799Z","steps":["trace[1640536887] 'agreement among raft nodes before linearized reading' (duration: 339.143069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:48:49.431884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:48:49.092467Z","time spent":"339.404136ms","remote":"127.0.0.1:60410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"info","ts":"2026-04-07T15:52:08.603827Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10543} {"level":"info","ts":"2026-04-07T15:52:08.682577Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10543,"took":"76.250303ms","hash":61546857,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":20885504,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-07T15:52:08.683181Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":61546857,"revision":10543,"compact-revision":8320}