{"level":"warn","ts":"2026-07-15T04:54:13.92341Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T04:54:13.923487Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.188: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.19.213.188:2380","--initial-cluster=instance=https://199.19.213.188:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.188:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.188: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-07-15T04:54:13.923552Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T04:54:13.92356Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.188:2380"]} {"level":"info","ts":"2026-07-15T04:54:13.923575Z","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-07-15T04:54:13.924272Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.188:2379"]} {"level":"info","ts":"2026-07-15T04:54:13.924372Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.188:2380"],"listen-peer-urls":["https://199.19.213.188:2380"],"advertise-client-urls":["https://199.19.213.188:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.188:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.188: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-07-15T04:54:13.928812Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.257372ms"} {"level":"info","ts":"2026-07-15T04:54:13.936464Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"201fe5d2d7e0161a","cluster-id":"92c0fc3a33fabd38"} {"level":"info","ts":"2026-07-15T04:54:13.936583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a switched to configuration voters=()"} {"level":"info","ts":"2026-07-15T04:54:13.936649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became follower at term 0"} {"level":"info","ts":"2026-07-15T04:54:13.936696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 201fe5d2d7e0161a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-15T04:54:13.936739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became follower at term 1"} {"level":"info","ts":"2026-07-15T04:54:13.936825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a switched to configuration voters=(2314821427219404314)"} {"level":"warn","ts":"2026-07-15T04:54:13.94563Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-15T04:54:13.948435Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-15T04:54:13.949726Z","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-07-15T04:54:13.953113Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"201fe5d2d7e0161a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-15T04:54:13.956355Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"201fe5d2d7e0161a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-15T04:54:13.95673Z","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-07-15T04:54:13.957578Z","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-07-15T04:54:13.958335Z","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-07-15T04:54:13.958286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a switched to configuration voters=(2314821427219404314)"} {"level":"info","ts":"2026-07-15T04:54:13.958545Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92c0fc3a33fabd38","local-member-id":"201fe5d2d7e0161a","added-peer-id":"201fe5d2d7e0161a","added-peer-peer-urls":["https://199.19.213.188:2380"]} {"level":"info","ts":"2026-07-15T04:54:13.959158Z","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-07-15T04:54:13.959289Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.188:2380"} {"level":"info","ts":"2026-07-15T04:54:13.959348Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.188:2380"} {"level":"info","ts":"2026-07-15T04:54:13.960075Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"201fe5d2d7e0161a","initial-advertise-peer-urls":["https://199.19.213.188:2380"],"listen-peer-urls":["https://199.19.213.188:2380"],"advertise-client-urls":["https://199.19.213.188:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.188:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-15T04:54:13.960141Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-15T04:54:14.937707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a is starting a new election at term 1"} {"level":"info","ts":"2026-07-15T04:54:14.937847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became pre-candidate at term 1"} {"level":"info","ts":"2026-07-15T04:54:14.937944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a received MsgPreVoteResp from 201fe5d2d7e0161a at term 1"} {"level":"info","ts":"2026-07-15T04:54:14.938014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became candidate at term 2"} {"level":"info","ts":"2026-07-15T04:54:14.93805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a received MsgVoteResp from 201fe5d2d7e0161a at term 2"} {"level":"info","ts":"2026-07-15T04:54:14.938126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"201fe5d2d7e0161a became leader at term 2"} {"level":"info","ts":"2026-07-15T04:54:14.938171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 201fe5d2d7e0161a elected leader 201fe5d2d7e0161a at term 2"} {"level":"info","ts":"2026-07-15T04:54:14.939447Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"201fe5d2d7e0161a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.188:2379]}","request-path":"/0/members/201fe5d2d7e0161a/attributes","cluster-id":"92c0fc3a33fabd38","publish-timeout":"7s"} {"level":"info","ts":"2026-07-15T04:54:14.939449Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T04:54:14.939516Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T04:54:14.939713Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-15T04:54:14.939749Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-15T04:54:14.93947Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:54:14.940909Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92c0fc3a33fabd38","local-member-id":"201fe5d2d7e0161a","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:54:14.941084Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:54:14.941163Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:54:14.943104Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-15T04:54:14.943434Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.188:2379"} {"level":"info","ts":"2026-07-15T04:54:57.923399Z","caller":"traceutil/trace.go:171","msg":"trace[1105508951] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"209.999516ms","start":"2026-07-15T04:54:57.713371Z","end":"2026-07-15T04:54:57.923371Z","steps":["trace[1105508951] 'process raft request' (duration: 209.772506ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:54:58.090847Z","caller":"traceutil/trace.go:171","msg":"trace[1461627900] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:649; }","duration":"186.819766ms","start":"2026-07-15T04:54:57.904003Z","end":"2026-07-15T04:54:58.090823Z","steps":["trace[1461627900] 'read index received' (duration: 19.175777ms)","trace[1461627900] 'applied index is now lower than readState.Index' (duration: 167.642989ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:54:58.090979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.954946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.188\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-15T04:54:58.091178Z","caller":"traceutil/trace.go:171","msg":"trace[1957388134] range","detail":"{range_begin:/registry/masterleases/199.19.213.188; range_end:; response_count:1; response_revision:633; }","duration":"187.210316ms","start":"2026-07-15T04:54:57.903956Z","end":"2026-07-15T04:54:58.091166Z","steps":["trace[1957388134] 'agreement among raft nodes before linearized reading' (duration: 186.961836ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:54:58.091137Z","caller":"traceutil/trace.go:171","msg":"trace[1012857963] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"280.617893ms","start":"2026-07-15T04:54:57.810489Z","end":"2026-07-15T04:54:58.091107Z","steps":["trace[1012857963] 'process raft request' (duration: 278.639356ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:54:58.316427Z","caller":"traceutil/trace.go:171","msg":"trace[1159737363] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"166.031693ms","start":"2026-07-15T04:54:58.150364Z","end":"2026-07-15T04:54:58.316396Z","steps":["trace[1159737363] 'process raft request' (duration: 165.851543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:54:58.909599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.392679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:54:58.909706Z","caller":"traceutil/trace.go:171","msg":"trace[131122220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"254.561069ms","start":"2026-07-15T04:54:58.655119Z","end":"2026-07-15T04:54:58.90968Z","steps":["trace[131122220] 'range keys from in-memory index tree' (duration: 254.340549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:54:58.90985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.809379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:54:58.909933Z","caller":"traceutil/trace.go:171","msg":"trace[1585367257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"334.933289ms","start":"2026-07-15T04:54:58.574978Z","end":"2026-07-15T04:54:58.909912Z","steps":["trace[1585367257] 'range keys from in-memory index tree' (duration: 334.744499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:54:58.910025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:54:58.574956Z","time spent":"335.011869ms","remote":"127.0.0.1:60212","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-07-15T04:54:58.944423Z","caller":"traceutil/trace.go:171","msg":"trace[2049658973] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"153.012165ms","start":"2026-07-15T04:54:58.791389Z","end":"2026-07-15T04:54:58.944402Z","steps":["trace[2049658973] 'read index received' (duration: 151.366778ms)","trace[2049658973] 'applied index is now lower than readState.Index' (duration: 1.644327ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:54:58.944475Z","caller":"traceutil/trace.go:171","msg":"trace[66070393] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"172.217732ms","start":"2026-07-15T04:54:58.772228Z","end":"2026-07-15T04:54:58.944445Z","steps":["trace[66070393] 'process raft request' (duration: 171.003964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:54:58.944573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.179604ms","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-07-15T04:54:58.944619Z","caller":"traceutil/trace.go:171","msg":"trace[1618187620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"153.307524ms","start":"2026-07-15T04:54:58.791303Z","end":"2026-07-15T04:54:58.944611Z","steps":["trace[1618187620] 'agreement among raft nodes before linearized reading' (duration: 153.171385ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:55:14.50988Z","caller":"traceutil/trace.go:171","msg":"trace[418433676] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"160.447365ms","start":"2026-07-15T04:55:14.349387Z","end":"2026-07-15T04:55:14.509835Z","steps":["trace[418433676] 'process raft request' (duration: 160.260715ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:55:45.715682Z","caller":"traceutil/trace.go:171","msg":"trace[790362062] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"105.738111ms","start":"2026-07-15T04:55:45.6099Z","end":"2026-07-15T04:55:45.715638Z","steps":["trace[790362062] 'process raft request' (duration: 103.602594ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:56:08.28131Z","caller":"traceutil/trace.go:171","msg":"trace[1280514762] transaction","detail":"{read_only:false; response_revision:1473; number_of_response:1; }","duration":"120.537707ms","start":"2026-07-15T04:56:08.160752Z","end":"2026-07-15T04:56:08.28129Z","steps":["trace[1280514762] 'process raft request' (duration: 120.409287ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:56:31.435538Z","caller":"traceutil/trace.go:171","msg":"trace[1928566390] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"238.755057ms","start":"2026-07-15T04:56:31.196749Z","end":"2026-07-15T04:56:31.435504Z","steps":["trace[1928566390] 'process raft request' (duration: 238.512307ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:56:31.453237Z","caller":"traceutil/trace.go:171","msg":"trace[982200566] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1917; }","duration":"123.522192ms","start":"2026-07-15T04:56:31.329682Z","end":"2026-07-15T04:56:31.453204Z","steps":["trace[982200566] 'read index received' (duration: 105.533212ms)","trace[982200566] 'applied index is now lower than readState.Index' (duration: 17.98786ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:56:31.453376Z","caller":"traceutil/trace.go:171","msg":"trace[1831293444] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"138.203557ms","start":"2026-07-15T04:56:31.315161Z","end":"2026-07-15T04:56:31.453364Z","steps":["trace[1831293444] 'process raft request' (duration: 137.919278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:56:31.454019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.308081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-07-15T04:56:31.454154Z","caller":"traceutil/trace.go:171","msg":"trace[1776061517] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1868; }","duration":"124.48368ms","start":"2026-07-15T04:56:31.329634Z","end":"2026-07-15T04:56:31.454118Z","steps":["trace[1776061517] 'agreement among raft nodes before linearized reading' (duration: 124.003991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:57:03.823155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.146757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-07-15T04:57:03.823196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.156177ms","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-07-15T04:57:03.823294Z","caller":"traceutil/trace.go:171","msg":"trace[1935842037] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2138; }","duration":"144.328796ms","start":"2026-07-15T04:57:03.678934Z","end":"2026-07-15T04:57:03.823263Z","steps":["trace[1935842037] 'range keys from in-memory index tree' (duration: 143.922997ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:57:03.823315Z","caller":"traceutil/trace.go:171","msg":"trace[344758642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2138; }","duration":"156.312576ms","start":"2026-07-15T04:57:03.666976Z","end":"2026-07-15T04:57:03.823289Z","steps":["trace[344758642] 'range keys from in-memory index tree' (duration: 155.968467ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:57:04.014766Z","caller":"traceutil/trace.go:171","msg":"trace[730125083] linearizableReadLoop","detail":"{readStateIndex:2204; appliedIndex:2203; }","duration":"143.688878ms","start":"2026-07-15T04:57:03.871048Z","end":"2026-07-15T04:57:04.014737Z","steps":["trace[730125083] 'read index received' (duration: 143.558108ms)","trace[730125083] 'applied index is now lower than readState.Index' (duration: 129.43µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:57:04.014911Z","caller":"traceutil/trace.go:171","msg":"trace[1424692728] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"184.587729ms","start":"2026-07-15T04:57:03.830279Z","end":"2026-07-15T04:57:04.014867Z","steps":["trace[1424692728] 'process raft request' (duration: 184.264069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:57:04.014957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.892498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:57:04.015011Z","caller":"traceutil/trace.go:171","msg":"trace[889197746] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2139; }","duration":"143.966808ms","start":"2026-07-15T04:57:03.871029Z","end":"2026-07-15T04:57:04.014996Z","steps":["trace[889197746] 'agreement among raft nodes before linearized reading' (duration: 143.809578ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:57:04.126572Z","caller":"traceutil/trace.go:171","msg":"trace[579298401] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"177.98002ms","start":"2026-07-15T04:57:03.948554Z","end":"2026-07-15T04:57:04.126534Z","steps":["trace[579298401] 'process raft request' (duration: 79.760286ms)","trace[579298401] 'compare' (duration: 98.061465ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:57:56.927906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.064446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-07-15T04:57:56.928019Z","caller":"traceutil/trace.go:171","msg":"trace[1647418983] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2603; }","duration":"270.230905ms","start":"2026-07-15T04:57:56.657766Z","end":"2026-07-15T04:57:56.927997Z","steps":["trace[1647418983] 'range keys from in-memory index tree' (duration: 269.882356ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:57:56.931869Z","caller":"traceutil/trace.go:171","msg":"trace[783727297] transaction","detail":"{read_only:false; response_revision:2604; number_of_response:1; }","duration":"254.367042ms","start":"2026-07-15T04:57:56.677467Z","end":"2026-07-15T04:57:56.931834Z","steps":["trace[783727297] 'process raft request' (duration: 254.090262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:58:24.180498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.828525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T04:58:24.18064Z","caller":"traceutil/trace.go:171","msg":"trace[1700944608] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"210.147276ms","start":"2026-07-15T04:58:23.970472Z","end":"2026-07-15T04:58:24.18062Z","steps":["trace[1700944608] 'process raft request' (duration: 81.529743ms)","trace[1700944608] 'compare' (duration: 127.729725ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:58:24.357756Z","caller":"traceutil/trace.go:171","msg":"trace[2042199212] transaction","detail":"{read_only:false; response_revision:2793; number_of_response:1; }","duration":"171.493171ms","start":"2026-07-15T04:58:24.18623Z","end":"2026-07-15T04:58:24.357723Z","steps":["trace[2042199212] 'process raft request' (duration: 126.428697ms)","trace[2042199212] 'compare' (duration: 44.911434ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:00:47.701811Z","caller":"traceutil/trace.go:171","msg":"trace[1702275296] transaction","detail":"{read_only:false; response_revision:4322; number_of_response:1; }","duration":"104.203965ms","start":"2026-07-15T05:00:47.59757Z","end":"2026-07-15T05:00:47.701774Z","steps":["trace[1702275296] 'process raft request' (duration: 74.410665ms)","trace[1702275296] 'compare' (duration: 29.473901ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:00:58.759926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.607051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T05:00:58.759958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.600116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-98815177-c77b-4fe8-9a9e-435b2a681d6a\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-15T05:00:58.760032Z","caller":"traceutil/trace.go:171","msg":"trace[1688347214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4619; }","duration":"183.800041ms","start":"2026-07-15T05:00:58.576209Z","end":"2026-07-15T05:00:58.760009Z","steps":["trace[1688347214] 'range keys from in-memory index tree' (duration: 183.515501ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:00:58.760052Z","caller":"traceutil/trace.go:171","msg":"trace[280828337] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-98815177-c77b-4fe8-9a9e-435b2a681d6a; range_end:; response_count:1; response_revision:4619; }","duration":"103.762435ms","start":"2026-07-15T05:00:58.65627Z","end":"2026-07-15T05:00:58.760032Z","steps":["trace[280828337] 'range keys from in-memory index tree' (duration: 103.250916ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:01:12.135604Z","caller":"traceutil/trace.go:171","msg":"trace[2093646547] linearizableReadLoop","detail":"{readStateIndex:5021; appliedIndex:5020; }","duration":"294.473843ms","start":"2026-07-15T05:01:11.841109Z","end":"2026-07-15T05:01:12.135583Z","steps":["trace[2093646547] 'read index received' (duration: 293.299525ms)","trace[2093646547] 'applied index is now lower than readState.Index' (duration: 1.173248ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:01:12.135798Z","caller":"traceutil/trace.go:171","msg":"trace[164198315] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"307.815541ms","start":"2026-07-15T05:01:11.827948Z","end":"2026-07-15T05:01:12.135764Z","steps":["trace[164198315] 'process raft request' (duration: 307.169502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:01:12.135964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:01:11.827894Z","time spent":"307.982581ms","remote":"127.0.0.1:60436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10794,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:01:12.136048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.878043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-07-15T05:01:12.136107Z","caller":"traceutil/trace.go:171","msg":"trace[149736045] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4819; }","duration":"295.001652ms","start":"2026-07-15T05:01:11.841091Z","end":"2026-07-15T05:01:12.136092Z","steps":["trace[149736045] 'agreement among raft nodes before linearized reading' (duration: 294.672413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:01:12.140249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.86751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-15T05:01:12.140324Z","caller":"traceutil/trace.go:171","msg":"trace[1083311292] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4820; }","duration":"244.006018ms","start":"2026-07-15T05:01:11.896299Z","end":"2026-07-15T05:01:12.140306Z","steps":["trace[1083311292] 'agreement among raft nodes before linearized reading' (duration: 243.812649ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:01:12.252846Z","caller":"traceutil/trace.go:171","msg":"trace[91538507] linearizableReadLoop","detail":"{readStateIndex:5026; appliedIndex:5022; }","duration":"107.412949ms","start":"2026-07-15T05:01:12.145402Z","end":"2026-07-15T05:01:12.252815Z","steps":["trace[91538507] 'read index received' (duration: 72.745688ms)","trace[91538507] 'applied index is now lower than readState.Index' (duration: 34.666041ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:01:12.254461Z","caller":"traceutil/trace.go:171","msg":"trace[863592628] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"109.213767ms","start":"2026-07-15T05:01:12.14521Z","end":"2026-07-15T05:01:12.254424Z","steps":["trace[863592628] 'process raft request' (duration: 107.41847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:01:12.254983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.489556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-98815177-c77b-4fe8-9a9e-435b2a681d6a\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-15T05:01:12.255044Z","caller":"traceutil/trace.go:171","msg":"trace[729830379] range","detail":"{range_begin:/registry/persistentvolumes/pvc-98815177-c77b-4fe8-9a9e-435b2a681d6a; range_end:; response_count:1; response_revision:4825; }","duration":"109.652896ms","start":"2026-07-15T05:01:12.145376Z","end":"2026-07-15T05:01:12.255029Z","steps":["trace[729830379] 'agreement among raft nodes before linearized reading' (duration: 107.558819ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:01:12.255246Z","caller":"traceutil/trace.go:171","msg":"trace[837919341] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"110.774523ms","start":"2026-07-15T05:01:12.14446Z","end":"2026-07-15T05:01:12.255234Z","steps":["trace[837919341] 'process raft request' (duration: 107.991328ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:01:12.255317Z","caller":"traceutil/trace.go:171","msg":"trace[805004807] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"110.022835ms","start":"2026-07-15T05:01:12.145277Z","end":"2026-07-15T05:01:12.2553Z","steps":["trace[805004807] 'process raft request' (duration: 107.436429ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:01:12.255686Z","caller":"traceutil/trace.go:171","msg":"trace[125641705] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"109.905475ms","start":"2026-07-15T05:01:12.145767Z","end":"2026-07-15T05:01:12.255673Z","steps":["trace[125641705] 'process raft request' (duration: 106.98745ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:01:12.669651Z","caller":"traceutil/trace.go:171","msg":"trace[958024448] linearizableReadLoop","detail":"{readStateIndex:5030; appliedIndex:5029; }","duration":"180.935455ms","start":"2026-07-15T05:01:12.488682Z","end":"2026-07-15T05:01:12.669617Z","steps":["trace[958024448] 'read index received' (duration: 180.661546ms)","trace[958024448] 'applied index is now lower than readState.Index' (duration: 272.479µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:01:12.669753Z","caller":"traceutil/trace.go:171","msg":"trace[108013313] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"196.874578ms","start":"2026-07-15T05:01:12.472845Z","end":"2026-07-15T05:01:12.669719Z","steps":["trace[108013313] 'process raft request' (duration: 196.576999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:01:12.670052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.301625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-15T05:01:12.670161Z","caller":"traceutil/trace.go:171","msg":"trace[1095454744] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4828; }","duration":"181.535235ms","start":"2026-07-15T05:01:12.488605Z","end":"2026-07-15T05:01:12.67014Z","steps":["trace[1095454744] 'agreement among raft nodes before linearized reading' (duration: 181.175095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:01:12.670164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.162547ms","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-07-15T05:01:12.67032Z","caller":"traceutil/trace.go:171","msg":"trace[1506667552] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4828; }","duration":"180.392176ms","start":"2026-07-15T05:01:12.489912Z","end":"2026-07-15T05:01:12.670304Z","steps":["trace[1506667552] 'agreement among raft nodes before linearized reading' (duration: 179.889667ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:01:12.77949Z","caller":"traceutil/trace.go:171","msg":"trace[186001966] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"102.837886ms","start":"2026-07-15T05:01:12.676638Z","end":"2026-07-15T05:01:12.779476Z","steps":["trace[186001966] 'process raft request' (duration: 102.805106ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:01:12.779597Z","caller":"traceutil/trace.go:171","msg":"trace[1542888835] transaction","detail":"{read_only:false; response_revision:4829; number_of_response:1; }","duration":"103.526875ms","start":"2026-07-15T05:01:12.676018Z","end":"2026-07-15T05:01:12.779545Z","steps":["trace[1542888835] 'process raft request' (duration: 63.280463ms)","trace[1542888835] 'compare' (duration: 40.062652ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:01:12.954908Z","caller":"traceutil/trace.go:171","msg":"trace[1453575397] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"107.473879ms","start":"2026-07-15T05:01:12.847389Z","end":"2026-07-15T05:01:12.954863Z","steps":["trace[1453575397] 'process raft request' (duration: 106.73861ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:01:13.67278Z","caller":"traceutil/trace.go:171","msg":"trace[1868134581] linearizableReadLoop","detail":"{readStateIndex:5036; appliedIndex:5035; }","duration":"246.433505ms","start":"2026-07-15T05:01:13.426311Z","end":"2026-07-15T05:01:13.672745Z","steps":["trace[1868134581] 'read index received' (duration: 246.068006ms)","trace[1868134581] 'applied index is now lower than readState.Index' (duration: 364.279µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:01:13.673102Z","caller":"traceutil/trace.go:171","msg":"trace[1033026517] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"290.88911ms","start":"2026-07-15T05:01:13.382199Z","end":"2026-07-15T05:01:13.673088Z","steps":["trace[1033026517] 'process raft request' (duration: 290.286172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:01:13.673641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.304004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-07-15T05:01:13.673743Z","caller":"traceutil/trace.go:171","msg":"trace[1941883468] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4833; }","duration":"247.438233ms","start":"2026-07-15T05:01:13.426277Z","end":"2026-07-15T05:01:13.673715Z","steps":["trace[1941883468] 'agreement among raft nodes before linearized reading' (duration: 247.159334ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:01:58.262072Z","caller":"traceutil/trace.go:171","msg":"trace[734839904] transaction","detail":"{read_only:false; response_revision:5272; number_of_response:1; }","duration":"103.947495ms","start":"2026-07-15T05:01:58.158078Z","end":"2026-07-15T05:01:58.262025Z","steps":["trace[734839904] 'process raft request' (duration: 103.651456ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:04:14.96441Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3227} {"level":"info","ts":"2026-07-15T05:04:15.063689Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3227,"took":"97.915955ms","hash":3266401452,"current-db-size-bytes":58458112,"current-db-size":"58 MB","current-db-size-in-use-bytes":31457280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T05:04:15.063748Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3266401452,"revision":3227,"compact-revision":-1}