{"level":"warn","ts":"2026-04-17T18:43:20.761793Z","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-17T18:43:20.761894Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-17T18:43:20.761974Z","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-17T18:43:20.76199Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]}
{"level":"info","ts":"2026-04-17T18:43:20.762006Z","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-17T18:43:20.762748Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]}
{"level":"info","ts":"2026-04-17T18:43:20.762893Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-17T18:43:20.768206Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.038853ms"}
{"level":"info","ts":"2026-04-17T18:43:20.777301Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"}
{"level":"info","ts":"2026-04-17T18:43:20.777469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"}
{"level":"info","ts":"2026-04-17T18:43:20.777513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"}
{"level":"info","ts":"2026-04-17T18:43:20.777528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-04-17T18:43:20.777541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"}
{"level":"info","ts":"2026-04-17T18:43:20.7776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"}
{"level":"warn","ts":"2026-04-17T18:43:20.784953Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-04-17T18:43:20.786875Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-04-17T18:43:20.788304Z","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-17T18:43:20.79065Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-04-17T18:43:20.790879Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-04-17T18:43:20.790996Z","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-17T18:43:20.791156Z","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-17T18:43:20.791177Z","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-17T18:43:20.793037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"}
{"level":"info","ts":"2026-04-17T18:43:20.79381Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]}
{"level":"info","ts":"2026-04-17T18:43:20.79736Z","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-17T18:43:20.797471Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"}
{"level":"info","ts":"2026-04-17T18:43:20.797542Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"}
{"level":"info","ts":"2026-04-17T18:43:20.799117Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-04-17T18:43:20.799175Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-04-17T18:43:21.27824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"}
{"level":"info","ts":"2026-04-17T18:43:21.278316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"}
{"level":"info","ts":"2026-04-17T18:43:21.27836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"}
{"level":"info","ts":"2026-04-17T18:43:21.278385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"}
{"level":"info","ts":"2026-04-17T18:43:21.278402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"}
{"level":"info","ts":"2026-04-17T18:43:21.278426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"}
{"level":"info","ts":"2026-04-17T18:43:21.278448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"}
{"level":"info","ts":"2026-04-17T18:43:21.280055Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-17T18:43:21.280069Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"}
{"level":"info","ts":"2026-04-17T18:43:21.280332Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-17T18:43:21.279683Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-17T18:43:21.281116Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-04-17T18:43:21.281225Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-04-17T18:43:21.282516Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-17T18:43:21.282631Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-17T18:43:21.282681Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-17T18:43:21.28398Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-04-17T18:43:21.286062Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"}
{"level":"warn","ts":"2026-04-17T18:43:38.522247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.2862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-17T18:43:38.52241Z","caller":"traceutil/trace.go:171","msg":"trace[1234279049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"262.525866ms","start":"2026-04-17T18:43:38.259853Z","end":"2026-04-17T18:43:38.522379Z","steps":["trace[1234279049] 'range keys from in-memory index tree'  (duration: 262.137657ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:43:38.721685Z","caller":"traceutil/trace.go:171","msg":"trace[714583103] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"193.395822ms","start":"2026-04-17T18:43:38.52825Z","end":"2026-04-17T18:43:38.721646Z","steps":["trace[714583103] 'process raft request'  (duration: 193.188947ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:43:58.760608Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725670642503115,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-04-17T18:43:59.066246Z","caller":"traceutil/trace.go:171","msg":"trace[2011620048] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"805.89372ms","start":"2026-04-17T18:43:58.260324Z","end":"2026-04-17T18:43:59.066217Z","steps":["trace[2011620048] 'read index received'  (duration: 805.298166ms)","trace[2011620048] 'applied index is now lower than readState.Index'  (duration: 594.374µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-17T18:43:59.066403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.059283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-17T18:43:59.066428Z","caller":"traceutil/trace.go:171","msg":"trace[1595536610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"806.135765ms","start":"2026-04-17T18:43:58.260283Z","end":"2026-04-17T18:43:59.066419Z","steps":["trace[1595536610] 'agreement among raft nodes before linearized reading'  (duration: 806.067514ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:43:59.066415Z","caller":"traceutil/trace.go:171","msg":"trace[832161978] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"856.84037ms","start":"2026-04-17T18:43:58.20953Z","end":"2026-04-17T18:43:59.066371Z","steps":["trace[832161978] 'process raft request'  (duration: 856.196426ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:43:59.066547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:43:58.26026Z","time spent":"806.193597ms","remote":"127.0.0.1:53788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-04-17T18:43:59.066663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.953741ms","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-17T18:43:59.066754Z","caller":"traceutil/trace.go:171","msg":"trace[1604383616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"121.068334ms","start":"2026-04-17T18:43:58.945663Z","end":"2026-04-17T18:43:59.066732Z","steps":["trace[1604383616] 'agreement among raft nodes before linearized reading'  (duration: 120.851169ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:43:59.066649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.038219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n4b9v\" ","response":"range_response_count:1 size:16965"}
{"level":"warn","ts":"2026-04-17T18:43:59.066868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.035756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-04-17T18:43:59.066991Z","caller":"traceutil/trace.go:171","msg":"trace[1329082882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"238.220489ms","start":"2026-04-17T18:43:58.828742Z","end":"2026-04-17T18:43:59.066962Z","steps":["trace[1329082882] 'agreement among raft nodes before linearized reading'  (duration: 237.862531ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:43:59.066895Z","caller":"traceutil/trace.go:171","msg":"trace[1169908113] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n4b9v; range_end:; response_count:1; response_revision:583; }","duration":"503.265554ms","start":"2026-04-17T18:43:58.56357Z","end":"2026-04-17T18:43:59.066836Z","steps":["trace[1169908113] 'agreement among raft nodes before linearized reading'  (duration: 502.825005ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:43:59.067086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:43:58.563509Z","time spent":"503.544421ms","remote":"127.0.0.1:53968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-n4b9v\" "}
{"level":"warn","ts":"2026-04-17T18:43:59.067206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:43:58.209499Z","time spent":"857.027225ms","remote":"127.0.0.1:53882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" mod_revision:578 > success:<request_put:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" value_size:1036201 >> failure:<request_range:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" > >"}
{"level":"info","ts":"2026-04-17T18:44:00.827548Z","caller":"traceutil/trace.go:171","msg":"trace[1080369219] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"104.429389ms","start":"2026-04-17T18:44:00.723084Z","end":"2026-04-17T18:44:00.827513Z","steps":["trace[1080369219] 'process raft request'  (duration: 104.259385ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:44:14.743878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.469925ms","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-17T18:44:14.744045Z","caller":"traceutil/trace.go:171","msg":"trace[819248860] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:743; }","duration":"153.749421ms","start":"2026-04-17T18:44:14.590276Z","end":"2026-04-17T18:44:14.744026Z","steps":["trace[819248860] 'range keys from in-memory index tree'  (duration: 153.157278ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:44:14.743897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.840892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"}
{"level":"info","ts":"2026-04-17T18:44:14.744366Z","caller":"traceutil/trace.go:171","msg":"trace[1537678773] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:743; }","duration":"107.384275ms","start":"2026-04-17T18:44:14.636965Z","end":"2026-04-17T18:44:14.744349Z","steps":["trace[1537678773] 'range keys from in-memory index tree'  (duration: 106.581467ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:45:27.939293Z","caller":"traceutil/trace.go:171","msg":"trace[623516310] linearizableReadLoop","detail":"{readStateIndex:1876; appliedIndex:1875; }","duration":"227.874936ms","start":"2026-04-17T18:45:27.711386Z","end":"2026-04-17T18:45:27.939261Z","steps":["trace[623516310] 'read index received'  (duration: 219.947109ms)","trace[623516310] 'applied index is now lower than readState.Index'  (duration: 7.926177ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T18:45:27.939441Z","caller":"traceutil/trace.go:171","msg":"trace[377078579] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"261.219039ms","start":"2026-04-17T18:45:27.678209Z","end":"2026-04-17T18:45:27.939428Z","steps":["trace[377078579] 'process raft request'  (duration: 253.094687ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:45:27.939615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.211603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"warn","ts":"2026-04-17T18:45:27.939641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.857821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"warn","ts":"2026-04-17T18:45:27.939742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.834719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"}
{"level":"info","ts":"2026-04-17T18:45:27.93973Z","caller":"traceutil/trace.go:171","msg":"trace[1527203912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1804; }","duration":"228.382497ms","start":"2026-04-17T18:45:27.711321Z","end":"2026-04-17T18:45:27.939704Z","steps":["trace[1527203912] 'agreement among raft nodes before linearized reading'  (duration: 228.129471ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:45:27.939804Z","caller":"traceutil/trace.go:171","msg":"trace[862664158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1804; }","duration":"148.008513ms","start":"2026-04-17T18:45:27.791785Z","end":"2026-04-17T18:45:27.939793Z","steps":["trace[862664158] 'agreement among raft nodes before linearized reading'  (duration: 147.718526ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:45:27.939923Z","caller":"traceutil/trace.go:171","msg":"trace[1358237485] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1804; }","duration":"209.007744ms","start":"2026-04-17T18:45:27.730702Z","end":"2026-04-17T18:45:27.93971Z","steps":["trace[1358237485] 'agreement among raft nodes before linearized reading'  (duration: 208.745048ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:45:28.229585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.717384ms","expected-duration":"100ms","prefix":"","request":"header:<ID:8471725670642505554 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:1774 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-17T18:45:28.229807Z","caller":"traceutil/trace.go:171","msg":"trace[1501275859] linearizableReadLoop","detail":"{readStateIndex:1879; appliedIndex:1876; }","duration":"243.163367ms","start":"2026-04-17T18:45:27.986625Z","end":"2026-04-17T18:45:28.229789Z","steps":["trace[1501275859] 'read index received'  (duration: 46.471867ms)","trace[1501275859] 'applied index is now lower than readState.Index'  (duration: 196.69018ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T18:45:28.229894Z","caller":"traceutil/trace.go:171","msg":"trace[1596502696] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"284.901529ms","start":"2026-04-17T18:45:27.944981Z","end":"2026-04-17T18:45:28.229882Z","steps":["trace[1596502696] 'process raft request'  (duration: 137.56013ms)","trace[1596502696] 'compare'  (duration: 146.49808ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T18:45:28.230095Z","caller":"traceutil/trace.go:171","msg":"trace[68724509] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"284.638453ms","start":"2026-04-17T18:45:27.945414Z","end":"2026-04-17T18:45:28.230052Z","steps":["trace[68724509] 'process raft request'  (duration: 284.301555ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:45:28.230203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.577096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"}
{"level":"info","ts":"2026-04-17T18:45:28.230275Z","caller":"traceutil/trace.go:171","msg":"trace[2097310322] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1807; }","duration":"243.692918ms","start":"2026-04-17T18:45:27.986561Z","end":"2026-04-17T18:45:28.230254Z","steps":["trace[2097310322] 'agreement among raft nodes before linearized reading'  (duration: 243.491054ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:45:28.236165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.04722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"}
{"level":"info","ts":"2026-04-17T18:45:28.236266Z","caller":"traceutil/trace.go:171","msg":"trace[732256034] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1808; }","duration":"194.207524ms","start":"2026-04-17T18:45:28.042036Z","end":"2026-04-17T18:45:28.236243Z","steps":["trace[732256034] 'agreement among raft nodes before linearized reading'  (duration: 193.942908ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:45:28.236332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.576483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"}
{"level":"info","ts":"2026-04-17T18:45:28.236373Z","caller":"traceutil/trace.go:171","msg":"trace[908927464] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1808; }","duration":"181.618373ms","start":"2026-04-17T18:45:28.054744Z","end":"2026-04-17T18:45:28.236362Z","steps":["trace[908927464] 'agreement among raft nodes before linearized reading'  (duration: 181.44864ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:45:28.236901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.370196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3cb3d7b4-a1b3-4522-ae50-b0801cee6ec8\" ","response":"range_response_count:1 size:3627"}
{"level":"info","ts":"2026-04-17T18:45:28.23703Z","caller":"traceutil/trace.go:171","msg":"trace[1404701169] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3cb3d7b4-a1b3-4522-ae50-b0801cee6ec8; range_end:; response_count:1; response_revision:1808; }","duration":"167.522349ms","start":"2026-04-17T18:45:28.069476Z","end":"2026-04-17T18:45:28.236998Z","steps":["trace[1404701169] 'agreement among raft nodes before linearized reading'  (duration: 166.685721ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:45:41.437118Z","caller":"traceutil/trace.go:171","msg":"trace[732716997] linearizableReadLoop","detail":"{readStateIndex:1991; appliedIndex:1990; }","duration":"109.711148ms","start":"2026-04-17T18:45:41.327384Z","end":"2026-04-17T18:45:41.437095Z","steps":["trace[732716997] 'read index received'  (duration: 109.526014ms)","trace[732716997] 'applied index is now lower than readState.Index'  (duration: 183.644µs)"],"step_count":2}
{"level":"info","ts":"2026-04-17T18:45:41.437234Z","caller":"traceutil/trace.go:171","msg":"trace[880729215] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"124.57267ms","start":"2026-04-17T18:45:41.312626Z","end":"2026-04-17T18:45:41.437199Z","steps":["trace[880729215] 'process raft request'  (duration: 124.318084ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:45:41.437445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.057516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"}
{"level":"info","ts":"2026-04-17T18:45:41.437538Z","caller":"traceutil/trace.go:171","msg":"trace[1108664986] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1916; }","duration":"110.205589ms","start":"2026-04-17T18:45:41.327315Z","end":"2026-04-17T18:45:41.43752Z","steps":["trace[1108664986] 'agreement among raft nodes before linearized reading'  (duration: 109.927453ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:46:17.963549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.436602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"}
{"level":"info","ts":"2026-04-17T18:46:17.963767Z","caller":"traceutil/trace.go:171","msg":"trace[1347598224] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2123; }","duration":"239.714618ms","start":"2026-04-17T18:46:17.724025Z","end":"2026-04-17T18:46:17.96374Z","steps":["trace[1347598224] 'range keys from in-memory index tree'  (duration: 239.168016ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:46:18.086353Z","caller":"traceutil/trace.go:171","msg":"trace[321609668] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"117.542668ms","start":"2026-04-17T18:46:17.968763Z","end":"2026-04-17T18:46:18.086305Z","steps":["trace[321609668] 'process raft request'  (duration: 117.238541ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:47:02.902722Z","caller":"traceutil/trace.go:171","msg":"trace[427320858] linearizableReadLoop","detail":"{readStateIndex:2507; appliedIndex:2506; }","duration":"172.095538ms","start":"2026-04-17T18:47:02.730605Z","end":"2026-04-17T18:47:02.902701Z","steps":["trace[427320858] 'read index received'  (duration: 171.948525ms)","trace[427320858] 'applied index is now lower than readState.Index'  (duration: 146.383µs)"],"step_count":2}
{"level":"info","ts":"2026-04-17T18:47:02.902774Z","caller":"traceutil/trace.go:171","msg":"trace[126677195] transaction","detail":"{read_only:false; response_revision:2415; number_of_response:1; }","duration":"198.003423ms","start":"2026-04-17T18:47:02.704748Z","end":"2026-04-17T18:47:02.902751Z","steps":["trace[126677195] 'process raft request'  (duration: 197.813459ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:47:02.902983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.348654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"}
{"level":"warn","ts":"2026-04-17T18:47:02.902989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.776504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"info","ts":"2026-04-17T18:47:02.903034Z","caller":"traceutil/trace.go:171","msg":"trace[1616631549] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2415; }","duration":"172.457036ms","start":"2026-04-17T18:47:02.730563Z","end":"2026-04-17T18:47:02.90302Z","steps":["trace[1616631549] 'agreement among raft nodes before linearized reading'  (duration: 172.267692ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:47:02.903043Z","caller":"traceutil/trace.go:171","msg":"trace[1890797549] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2415; }","duration":"168.840385ms","start":"2026-04-17T18:47:02.734185Z","end":"2026-04-17T18:47:02.903026Z","steps":["trace[1890797549] 'agreement among raft nodes before linearized reading'  (duration: 168.692542ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:47:36.239702Z","caller":"traceutil/trace.go:171","msg":"trace[593698697] transaction","detail":"{read_only:false; response_revision:2599; number_of_response:1; }","duration":"157.065791ms","start":"2026-04-17T18:47:36.082598Z","end":"2026-04-17T18:47:36.239664Z","steps":["trace[593698697] 'process raft request'  (duration: 141.004514ms)","trace[593698697] 'compare'  (duration: 15.658618ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T18:51:44.066572Z","caller":"traceutil/trace.go:171","msg":"trace[25078316] linearizableReadLoop","detail":"{readStateIndex:4719; appliedIndex:4717; }","duration":"110.603997ms","start":"2026-04-17T18:51:43.95593Z","end":"2026-04-17T18:51:44.066534Z","steps":["trace[25078316] 'read index received'  (duration: 92.898559ms)","trace[25078316] 'applied index is now lower than readState.Index'  (duration: 17.704048ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T18:51:44.066754Z","caller":"traceutil/trace.go:171","msg":"trace[2065127899] transaction","detail":"{read_only:false; response_revision:4542; number_of_response:1; }","duration":"112.126101ms","start":"2026-04-17T18:51:43.954583Z","end":"2026-04-17T18:51:44.066709Z","steps":["trace[2065127899] 'process raft request'  (duration: 111.777103ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:51:44.06678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.838892ms","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-17T18:51:44.067098Z","caller":"traceutil/trace.go:171","msg":"trace[422198334] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:0; response_revision:4542; }","duration":"111.22861ms","start":"2026-04-17T18:51:43.955856Z","end":"2026-04-17T18:51:44.067084Z","steps":["trace[422198334] 'agreement among raft nodes before linearized reading'  (duration: 110.846472ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:51:49.289455Z","caller":"traceutil/trace.go:171","msg":"trace[1770425780] linearizableReadLoop","detail":"{readStateIndex:4885; appliedIndex:4885; }","duration":"102.412498ms","start":"2026-04-17T18:51:49.186976Z","end":"2026-04-17T18:51:49.289388Z","steps":["trace[1770425780] 'read index received'  (duration: 102.405258ms)","trace[1770425780] 'applied index is now lower than readState.Index'  (duration: 5.69µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-17T18:51:49.289546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.544741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-17T18:51:49.289605Z","caller":"traceutil/trace.go:171","msg":"trace[184262331] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4705; }","duration":"102.650593ms","start":"2026-04-17T18:51:49.186942Z","end":"2026-04-17T18:51:49.289593Z","steps":["trace[184262331] 'agreement among raft nodes before linearized reading'  (duration: 102.539211ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:51:49.28934Z","caller":"traceutil/trace.go:171","msg":"trace[654986300] transaction","detail":"{read_only:false; response_revision:4705; number_of_response:1; }","duration":"103.943431ms","start":"2026-04-17T18:51:49.18537Z","end":"2026-04-17T18:51:49.289313Z","steps":["trace[654986300] 'process raft request'  (duration: 103.774808ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:51:52.347377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.408947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-04-17T18:51:52.34751Z","caller":"traceutil/trace.go:171","msg":"trace[651787244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4840; }","duration":"138.578991ms","start":"2026-04-17T18:51:52.208905Z","end":"2026-04-17T18:51:52.347484Z","steps":["trace[651787244] 'range keys from in-memory index tree'  (duration: 138.205963ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:51:52.347937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.624498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8bb6s\" ","response":"range_response_count:1 size:6531"}
{"level":"warn","ts":"2026-04-17T18:51:52.347997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.422643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"}
{"level":"info","ts":"2026-04-17T18:51:52.348026Z","caller":"traceutil/trace.go:171","msg":"trace[1345649884] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8bb6s; range_end:; response_count:1; response_revision:4840; }","duration":"234.771842ms","start":"2026-04-17T18:51:52.113237Z","end":"2026-04-17T18:51:52.348009Z","steps":["trace[1345649884] 'range keys from in-memory index tree'  (duration: 234.487945ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:51:52.348064Z","caller":"traceutil/trace.go:171","msg":"trace[285319623] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4840; }","duration":"172.509075ms","start":"2026-04-17T18:51:52.175539Z","end":"2026-04-17T18:51:52.348048Z","steps":["trace[285319623] 'range keys from in-memory index tree'  (duration: 172.201649ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:51:55.989354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.538508ms","expected-duration":"100ms","prefix":"","request":"header:<ID:8471725670642513432 username:\"kube-apiserver-etcd-client\" auth_revision:1 > lease_grant:<ttl:3660-second id:75919d9cc1347617>","response":"size:42"}
{"level":"info","ts":"2026-04-17T18:51:55.989494Z","caller":"traceutil/trace.go:171","msg":"trace[1026214445] linearizableReadLoop","detail":"{readStateIndex:5098; appliedIndex:5097; }","duration":"129.155744ms","start":"2026-04-17T18:51:55.860317Z","end":"2026-04-17T18:51:55.989473Z","steps":["trace[1026214445] 'read index received'  (duration: 27.510054ms)","trace[1026214445] 'applied index is now lower than readState.Index'  (duration: 101.64367ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-17T18:51:55.989747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.4158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-51b158cb-6ac3-4689-96ef-322e342e7639\" ","response":"range_response_count:1 size:3906"}
{"level":"info","ts":"2026-04-17T18:51:55.989784Z","caller":"traceutil/trace.go:171","msg":"trace[718951046] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-51b158cb-6ac3-4689-96ef-322e342e7639; range_end:; response_count:1; response_revision:4911; }","duration":"129.499792ms","start":"2026-04-17T18:51:55.860272Z","end":"2026-04-17T18:51:55.989771Z","steps":["trace[718951046] 'agreement among raft nodes before linearized reading'  (duration: 129.316178ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:52:04.461337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.630251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-17T18:52:04.461457Z","caller":"traceutil/trace.go:171","msg":"trace[669445251] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:5065; }","duration":"132.837116ms","start":"2026-04-17T18:52:04.328597Z","end":"2026-04-17T18:52:04.461435Z","steps":["trace[669445251] 'count revisions from in-memory index tree'  (duration: 132.410927ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:52:04.581846Z","caller":"traceutil/trace.go:171","msg":"trace[1439430132] linearizableReadLoop","detail":"{readStateIndex:5257; appliedIndex:5256; }","duration":"115.218339ms","start":"2026-04-17T18:52:04.466605Z","end":"2026-04-17T18:52:04.581823Z","steps":["trace[1439430132] 'read index received'  (duration: 114.987533ms)","trace[1439430132] 'applied index is now lower than readState.Index'  (duration: 229.546µs)"],"step_count":2}
{"level":"info","ts":"2026-04-17T18:52:04.582143Z","caller":"traceutil/trace.go:171","msg":"trace[1946116652] transaction","detail":"{read_only:false; response_revision:5066; number_of_response:1; }","duration":"116.460446ms","start":"2026-04-17T18:52:04.465668Z","end":"2026-04-17T18:52:04.582129Z","steps":["trace[1946116652] 'process raft request'  (duration: 115.986035ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:52:04.582446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.813042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"info","ts":"2026-04-17T18:52:04.582576Z","caller":"traceutil/trace.go:171","msg":"trace[787806200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5066; }","duration":"115.979796ms","start":"2026-04-17T18:52:04.466575Z","end":"2026-04-17T18:52:04.582555Z","steps":["trace[787806200] 'agreement among raft nodes before linearized reading'  (duration: 115.658259ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:52:04.604369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.26025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:53908"}
{"level":"info","ts":"2026-04-17T18:52:04.604434Z","caller":"traceutil/trace.go:171","msg":"trace[798067723] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5067; }","duration":"136.363523ms","start":"2026-04-17T18:52:04.468055Z","end":"2026-04-17T18:52:04.604419Z","steps":["trace[798067723] 'agreement among raft nodes before linearized reading'  (duration: 136.119448ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:52:04.604886Z","caller":"traceutil/trace.go:171","msg":"trace[1460370683] transaction","detail":"{read_only:false; response_revision:5067; number_of_response:1; }","duration":"137.104879ms","start":"2026-04-17T18:52:04.467769Z","end":"2026-04-17T18:52:04.604874Z","steps":["trace[1460370683] 'process raft request'  (duration: 136.318672ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:52:12.328486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.335292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0cecabab-80e9-4baf-b9d0-b876f88a96c4\" ","response":"range_response_count:1 size:3646"}
{"level":"info","ts":"2026-04-17T18:52:12.328604Z","caller":"traceutil/trace.go:171","msg":"trace[1378466165] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0cecabab-80e9-4baf-b9d0-b876f88a96c4; range_end:; response_count:1; response_revision:5209; }","duration":"110.517486ms","start":"2026-04-17T18:52:12.218062Z","end":"2026-04-17T18:52:12.328579Z","steps":["trace[1378466165] 'range keys from in-memory index tree'  (duration: 110.052775ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:52:12.686832Z","caller":"traceutil/trace.go:171","msg":"trace[1135905435] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"351.793121ms","start":"2026-04-17T18:52:12.334987Z","end":"2026-04-17T18:52:12.68678Z","steps":["trace[1135905435] 'process raft request'  (duration: 351.588587ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:52:12.687134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:52:12.334953Z","time spent":"352.085837ms","remote":"127.0.0.1:53968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3748,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0cecabab-80e9-4baf-b9d0-b876f88a96c4\" mod_revision:5196 > success:<request_put:<key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0cecabab-80e9-4baf-b9d0-b876f88a96c4\" value_size:3648 >> failure:<request_range:<key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0cecabab-80e9-4baf-b9d0-b876f88a96c4\" > >"}
{"level":"info","ts":"2026-04-17T18:52:12.717801Z","caller":"traceutil/trace.go:171","msg":"trace[1567869981] transaction","detail":"{read_only:false; response_revision:5211; number_of_response:1; }","duration":"377.481204ms","start":"2026-04-17T18:52:12.340285Z","end":"2026-04-17T18:52:12.717766Z","steps":["trace[1567869981] 'process raft request'  (duration: 377.226649ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:52:12.717806Z","caller":"traceutil/trace.go:171","msg":"trace[506280194] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"101.241461ms","start":"2026-04-17T18:52:12.616534Z","end":"2026-04-17T18:52:12.717776Z","steps":["trace[506280194] 'process raft request'  (duration: 101.140299ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:52:12.71798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:52:12.340265Z","time spent":"377.620848ms","remote":"127.0.0.1:54046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:5021 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"info","ts":"2026-04-17T18:53:21.522784Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2840}
{"level":"info","ts":"2026-04-17T18:53:21.590435Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2840,"took":"66.242003ms","hash":3382982157,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":32817152,"current-db-size-in-use":"33 MB"}
{"level":"info","ts":"2026-04-17T18:53:21.590562Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3382982157,"revision":2840,"compact-revision":-1}
{"level":"info","ts":"2026-04-17T18:58:06.256203Z","caller":"traceutil/trace.go:171","msg":"trace[33718662] linearizableReadLoop","detail":"{readStateIndex:8297; appliedIndex:8296; }","duration":"168.790747ms","start":"2026-04-17T18:58:06.087364Z","end":"2026-04-17T18:58:06.256155Z","steps":["trace[33718662] 'read index received'  (duration: 168.48507ms)","trace[33718662] 'applied index is now lower than readState.Index'  (duration: 303.546µs)"],"step_count":2}
{"level":"info","ts":"2026-04-17T18:58:06.256307Z","caller":"traceutil/trace.go:171","msg":"trace[706983751] transaction","detail":"{read_only:false; response_revision:8015; number_of_response:1; }","duration":"243.455515ms","start":"2026-04-17T18:58:06.012817Z","end":"2026-04-17T18:58:06.256273Z","steps":["trace[706983751] 'process raft request'  (duration: 243.066047ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:58:06.256552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.167625ms","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-17T18:58:06.256638Z","caller":"traceutil/trace.go:171","msg":"trace[213179712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8015; }","duration":"169.323599ms","start":"2026-04-17T18:58:06.087297Z","end":"2026-04-17T18:58:06.256621Z","steps":["trace[213179712] 'agreement among raft nodes before linearized reading'  (duration: 169.075924ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:58:06.406769Z","caller":"traceutil/trace.go:171","msg":"trace[1268983157] transaction","detail":"{read_only:false; response_revision:8018; number_of_response:1; }","duration":"146.407733ms","start":"2026-04-17T18:58:06.260344Z","end":"2026-04-17T18:58:06.406752Z","steps":["trace[1268983157] 'process raft request'  (duration: 146.381433ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:58:06.407055Z","caller":"traceutil/trace.go:171","msg":"trace[1041520130] transaction","detail":"{read_only:false; response_revision:8017; number_of_response:1; }","duration":"180.966941ms","start":"2026-04-17T18:58:06.226083Z","end":"2026-04-17T18:58:06.407049Z","steps":["trace[1041520130] 'process raft request'  (duration: 180.567992ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:58:06.407157Z","caller":"traceutil/trace.go:171","msg":"trace[1348226723] linearizableReadLoop","detail":"{readStateIndex:8299; appliedIndex:8297; }","duration":"150.796588ms","start":"2026-04-17T18:58:06.256353Z","end":"2026-04-17T18:58:06.407149Z","steps":["trace[1348226723] 'read index received'  (duration: 146.423962ms)","trace[1348226723] 'applied index is now lower than readState.Index'  (duration: 4.371426ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T18:58:06.40724Z","caller":"traceutil/trace.go:171","msg":"trace[1435244101] transaction","detail":"{read_only:false; response_revision:8016; number_of_response:1; }","duration":"286.388346ms","start":"2026-04-17T18:58:06.120816Z","end":"2026-04-17T18:58:06.407204Z","steps":["trace[1435244101] 'process raft request'  (duration: 281.945419ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:58:06.407325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.165484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-17T18:58:06.407347Z","caller":"traceutil/trace.go:171","msg":"trace[1321199970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8018; }","duration":"150.217516ms","start":"2026-04-17T18:58:06.257123Z","end":"2026-04-17T18:58:06.40734Z","steps":["trace[1321199970] 'agreement among raft nodes before linearized reading'  (duration: 150.190725ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:58:06.407518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.19312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/key-manager.18a739eddaa88d53\" ","response":"range_response_count:1 size:679"}
{"level":"info","ts":"2026-04-17T18:58:06.407538Z","caller":"traceutil/trace.go:171","msg":"trace[1296673471] range","detail":"{range_begin:/registry/events/openstack/key-manager.18a739eddaa88d53; range_end:; response_count:1; response_revision:8018; }","duration":"147.248441ms","start":"2026-04-17T18:58:06.260286Z","end":"2026-04-17T18:58:06.407534Z","steps":["trace[1296673471] 'agreement among raft nodes before linearized reading'  (duration: 147.176419ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:58:06.407294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.61156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-17T18:58:06.407637Z","caller":"traceutil/trace.go:171","msg":"trace[132735862] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8018; }","duration":"293.014129ms","start":"2026-04-17T18:58:06.114603Z","end":"2026-04-17T18:58:06.407617Z","steps":["trace[132735862] 'agreement among raft nodes before linearized reading'  (duration: 292.645112ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:58:17.007462Z","caller":"traceutil/trace.go:171","msg":"trace[1838254172] linearizableReadLoop","detail":"{readStateIndex:8357; appliedIndex:8356; }","duration":"203.663983ms","start":"2026-04-17T18:58:16.803768Z","end":"2026-04-17T18:58:17.007432Z","steps":["trace[1838254172] 'read index received'  (duration: 168.234025ms)","trace[1838254172] 'applied index is now lower than readState.Index'  (duration: 35.428668ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T18:58:17.007526Z","caller":"traceutil/trace.go:171","msg":"trace[1311004208] transaction","detail":"{read_only:false; response_revision:8073; number_of_response:1; }","duration":"204.738426ms","start":"2026-04-17T18:58:16.802752Z","end":"2026-04-17T18:58:17.00749Z","steps":["trace[1311004208] 'process raft request'  (duration: 169.203115ms)","trace[1311004208] 'compare'  (duration: 35.351057ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-17T18:58:17.007764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.98566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"}
{"level":"warn","ts":"2026-04-17T18:58:17.007791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.665687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8bb6s\" ","response":"range_response_count:1 size:6531"}
{"level":"warn","ts":"2026-04-17T18:58:17.007791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.170728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-04-17T18:58:17.007827Z","caller":"traceutil/trace.go:171","msg":"trace[438195904] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8073; }","duration":"204.097303ms","start":"2026-04-17T18:58:16.803716Z","end":"2026-04-17T18:58:17.007813Z","steps":["trace[438195904] 'agreement among raft nodes before linearized reading'  (duration: 203.863768ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:58:17.007852Z","caller":"traceutil/trace.go:171","msg":"trace[1667799243] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8bb6s; range_end:; response_count:1; response_revision:8073; }","duration":"127.781879ms","start":"2026-04-17T18:58:16.880053Z","end":"2026-04-17T18:58:17.007835Z","steps":["trace[1667799243] 'agreement among raft nodes before linearized reading'  (duration: 127.590485ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:58:17.007868Z","caller":"traceutil/trace.go:171","msg":"trace[1335551121] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8073; }","duration":"200.27909ms","start":"2026-04-17T18:58:16.807574Z","end":"2026-04-17T18:58:17.007853Z","steps":["trace[1335551121] 'agreement among raft nodes before linearized reading'  (duration: 200.020125ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:58:18.93389Z","caller":"traceutil/trace.go:171","msg":"trace[584238996] transaction","detail":"{read_only:false; response_revision:8081; number_of_response:1; }","duration":"153.222349ms","start":"2026-04-17T18:58:18.780637Z","end":"2026-04-17T18:58:18.933859Z","steps":["trace[584238996] 'process raft request'  (duration: 79.609215ms)","trace[584238996] 'compare'  (duration: 73.495262ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T18:58:19.303893Z","caller":"traceutil/trace.go:171","msg":"trace[388015380] linearizableReadLoop","detail":"{readStateIndex:8366; appliedIndex:8365; }","duration":"285.915755ms","start":"2026-04-17T18:58:19.017952Z","end":"2026-04-17T18:58:19.303868Z","steps":["trace[388015380] 'read index received'  (duration: 285.637479ms)","trace[388015380] 'applied index is now lower than readState.Index'  (duration: 276.346µs)"],"step_count":2}
{"level":"info","ts":"2026-04-17T18:58:19.303994Z","caller":"traceutil/trace.go:171","msg":"trace[1653955310] transaction","detail":"{read_only:false; response_revision:8082; number_of_response:1; }","duration":"286.994758ms","start":"2026-04-17T18:58:19.016955Z","end":"2026-04-17T18:58:19.30395Z","steps":["trace[1653955310] 'process raft request'  (duration: 286.733052ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:58:19.304156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.177171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8bb6s\" ","response":"range_response_count:1 size:6531"}
{"level":"info","ts":"2026-04-17T18:58:19.30423Z","caller":"traceutil/trace.go:171","msg":"trace[1263853028] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8bb6s; range_end:; response_count:1; response_revision:8082; }","duration":"286.288284ms","start":"2026-04-17T18:58:19.017925Z","end":"2026-04-17T18:58:19.304213Z","steps":["trace[1263853028] 'agreement among raft nodes before linearized reading'  (duration: 286.076608ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:58:19.304259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.552671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-04-17T18:58:19.30433Z","caller":"traceutil/trace.go:171","msg":"trace[1657166665] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8082; }","duration":"269.646433ms","start":"2026-04-17T18:58:19.034665Z","end":"2026-04-17T18:58:19.304311Z","steps":["trace[1657166665] 'agreement among raft nodes before linearized reading'  (duration: 269.469708ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:58:19.304462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.259188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"}
{"level":"info","ts":"2026-04-17T18:58:19.304562Z","caller":"traceutil/trace.go:171","msg":"trace[1162017587] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8082; }","duration":"283.431642ms","start":"2026-04-17T18:58:19.021106Z","end":"2026-04-17T18:58:19.304538Z","steps":["trace[1162017587] 'agreement among raft nodes before linearized reading'  (duration: 282.980511ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:58:19.485771Z","caller":"traceutil/trace.go:171","msg":"trace[768201064] transaction","detail":"{read_only:false; response_revision:8085; number_of_response:1; }","duration":"150.92145ms","start":"2026-04-17T18:58:19.334817Z","end":"2026-04-17T18:58:19.485738Z","steps":["trace[768201064] 'process raft request'  (duration: 85.342049ms)","trace[768201064] 'compare'  (duration: 65.244694ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T18:58:20.078723Z","caller":"traceutil/trace.go:171","msg":"trace[675754016] transaction","detail":"{read_only:false; response_revision:8087; number_of_response:1; }","duration":"146.498064ms","start":"2026-04-17T18:58:19.932204Z","end":"2026-04-17T18:58:20.078702Z","steps":["trace[675754016] 'process raft request'  (duration: 146.361811ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:58:21.533195Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5905}
{"level":"info","ts":"2026-04-17T18:58:21.634012Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5905,"took":"98.124516ms","hash":3983594300,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":26447872,"current-db-size-in-use":"26 MB"}
{"level":"info","ts":"2026-04-17T18:58:21.634089Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3983594300,"revision":5905,"compact-revision":2840}
{"level":"info","ts":"2026-04-17T18:58:47.280241Z","caller":"traceutil/trace.go:171","msg":"trace[1208867294] linearizableReadLoop","detail":"{readStateIndex:8578; appliedIndex:8577; }","duration":"211.320858ms","start":"2026-04-17T18:58:47.068883Z","end":"2026-04-17T18:58:47.280204Z","steps":["trace[1208867294] 'read index received'  (duration: 211.005631ms)","trace[1208867294] 'applied index is now lower than readState.Index'  (duration: 314.147µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-17T18:58:47.2806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.682126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-04-17T18:58:47.280663Z","caller":"traceutil/trace.go:171","msg":"trace[1392975120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8285; }","duration":"211.830859ms","start":"2026-04-17T18:58:47.068812Z","end":"2026-04-17T18:58:47.280643Z","steps":["trace[1392975120] 'agreement among raft nodes before linearized reading'  (duration: 211.550093ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:58:47.281092Z","caller":"traceutil/trace.go:171","msg":"trace[1278553661] transaction","detail":"{read_only:false; response_revision:8285; number_of_response:1; }","duration":"330.344188ms","start":"2026-04-17T18:58:46.950735Z","end":"2026-04-17T18:58:47.281079Z","steps":["trace[1278553661] 'process raft request'  (duration: 329.267495ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:58:47.281228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:58:46.950717Z","time spent":"330.422069ms","remote":"127.0.0.1:54046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:8276 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-04-17T18:59:00.822375Z","caller":"traceutil/trace.go:171","msg":"trace[475806177] linearizableReadLoop","detail":"{readStateIndex:8701; appliedIndex:8700; }","duration":"112.01025ms","start":"2026-04-17T18:59:00.710327Z","end":"2026-04-17T18:59:00.822338Z","steps":["trace[475806177] 'read index received'  (duration: 111.811926ms)","trace[475806177] 'applied index is now lower than readState.Index'  (duration: 196.564µs)"],"step_count":2}
{"level":"info","ts":"2026-04-17T18:59:00.82255Z","caller":"traceutil/trace.go:171","msg":"trace[1752742283] transaction","detail":"{read_only:false; response_revision:8400; number_of_response:1; }","duration":"118.633452ms","start":"2026-04-17T18:59:00.703819Z","end":"2026-04-17T18:59:00.822452Z","steps":["trace[1752742283] 'process raft request'  (duration: 118.327406ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:59:00.822768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.398768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"info","ts":"2026-04-17T18:59:00.822832Z","caller":"traceutil/trace.go:171","msg":"trace[1901353354] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8400; }","duration":"112.556692ms","start":"2026-04-17T18:59:00.710259Z","end":"2026-04-17T18:59:00.822816Z","steps":["trace[1901353354] 'agreement among raft nodes before linearized reading'  (duration: 112.285456ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:59:00.840777Z","caller":"traceutil/trace.go:171","msg":"trace[1028389998] transaction","detail":"{read_only:false; response_revision:8401; number_of_response:1; }","duration":"123.057509ms","start":"2026-04-17T18:59:00.717684Z","end":"2026-04-17T18:59:00.840741Z","steps":["trace[1028389998] 'process raft request'  (duration: 122.788773ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:59:00.840942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.048411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"warn","ts":"2026-04-17T18:59:00.840959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.678602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-x2ngl\" ","response":"range_response_count:1 size:4653"}
{"level":"info","ts":"2026-04-17T18:59:00.841009Z","caller":"traceutil/trace.go:171","msg":"trace[93789976] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8401; }","duration":"118.172133ms","start":"2026-04-17T18:59:00.722823Z","end":"2026-04-17T18:59:00.840995Z","steps":["trace[93789976] 'agreement among raft nodes before linearized reading'  (duration: 118.01493ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:59:00.841068Z","caller":"traceutil/trace.go:171","msg":"trace[458206258] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-x2ngl; range_end:; response_count:1; response_revision:8401; }","duration":"123.830545ms","start":"2026-04-17T18:59:00.717214Z","end":"2026-04-17T18:59:00.841045Z","steps":["trace[458206258] 'agreement among raft nodes before linearized reading'  (duration: 123.430957ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:59:01.728135Z","caller":"traceutil/trace.go:171","msg":"trace[1793305596] linearizableReadLoop","detail":"{readStateIndex:8706; appliedIndex:8705; }","duration":"260.060658ms","start":"2026-04-17T18:59:01.468047Z","end":"2026-04-17T18:59:01.728108Z","steps":["trace[1793305596] 'read index received'  (duration: 169.511862ms)","trace[1793305596] 'applied index is now lower than readState.Index'  (duration: 90.547476ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T18:59:01.728401Z","caller":"traceutil/trace.go:171","msg":"trace[464637856] transaction","detail":"{read_only:false; response_revision:8405; number_of_response:1; }","duration":"326.412643ms","start":"2026-04-17T18:59:01.401976Z","end":"2026-04-17T18:59:01.728389Z","steps":["trace[464637856] 'process raft request'  (duration: 235.630241ms)","trace[464637856] 'compare'  (duration: 90.340252ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-17T18:59:01.728534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:59:01.401946Z","time spent":"326.488204ms","remote":"127.0.0.1:54046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:8380 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-04-17T18:59:01.728611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.537589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"}
{"level":"info","ts":"2026-04-17T18:59:01.728703Z","caller":"traceutil/trace.go:171","msg":"trace[1701882171] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8405; }","duration":"260.687342ms","start":"2026-04-17T18:59:01.467994Z","end":"2026-04-17T18:59:01.728681Z","steps":["trace[1701882171] 'agreement among raft nodes before linearized reading'  (duration: 260.402106ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:59:01.743114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.2734ms","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-17T18:59:01.743191Z","caller":"traceutil/trace.go:171","msg":"trace[1845567922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8406; }","duration":"208.381293ms","start":"2026-04-17T18:59:01.534792Z","end":"2026-04-17T18:59:01.743173Z","steps":["trace[1845567922] 'agreement among raft nodes before linearized reading'  (duration: 208.185679ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:59:01.743135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.237314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8bb6s\" ","response":"range_response_count:1 size:6531"}
{"level":"info","ts":"2026-04-17T18:59:01.743242Z","caller":"traceutil/trace.go:171","msg":"trace[169095930] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8bb6s; range_end:; response_count:1; response_revision:8406; }","duration":"118.403827ms","start":"2026-04-17T18:59:01.624828Z","end":"2026-04-17T18:59:01.743232Z","steps":["trace[169095930] 'agreement among raft nodes before linearized reading'  (duration: 118.181723ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T18:59:01.743365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.160579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"}
{"level":"warn","ts":"2026-04-17T18:59:01.743364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.8453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-04-17T18:59:01.743441Z","caller":"traceutil/trace.go:171","msg":"trace[360235771] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8406; }","duration":"128.257931ms","start":"2026-04-17T18:59:01.615161Z","end":"2026-04-17T18:59:01.743419Z","steps":["trace[360235771] 'agreement among raft nodes before linearized reading'  (duration: 128.070267ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:59:01.743479Z","caller":"traceutil/trace.go:171","msg":"trace[1236723574] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8406; }","duration":"121.048275ms","start":"2026-04-17T18:59:01.622399Z","end":"2026-04-17T18:59:01.743447Z","steps":["trace[1236723574] 'agreement among raft nodes before linearized reading'  (duration: 120.760349ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:59:02.384957Z","caller":"traceutil/trace.go:171","msg":"trace[1750306337] transaction","detail":"{read_only:false; response_revision:8413; number_of_response:1; }","duration":"114.446683ms","start":"2026-04-17T18:59:02.270491Z","end":"2026-04-17T18:59:02.384938Z","steps":["trace[1750306337] 'process raft request'  (duration: 114.251329ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T18:59:49.94045Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002409.wal"}
{"level":"info","ts":"2026-04-17T19:00:07.878548Z","caller":"traceutil/trace.go:171","msg":"trace[1893262432] transaction","detail":"{read_only:false; response_revision:9035; number_of_response:1; }","duration":"133.597167ms","start":"2026-04-17T19:00:07.744927Z","end":"2026-04-17T19:00:07.878524Z","steps":["trace[1893262432] 'process raft request'  (duration: 133.424243ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T19:01:23.589204Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f3d52791b64f7591","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-04-17T19:01:23.59523Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-04-17T19:01:23.595354Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-04-17T19:03:21.540481Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8105}
{"level":"info","ts":"2026-04-17T19:03:21.605612Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8105,"took":"62.674397ms","hash":311889259,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":29413376,"current-db-size-in-use":"29 MB"}
{"level":"info","ts":"2026-04-17T19:03:21.605684Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":311889259,"revision":8105,"compact-revision":5905}
{"level":"info","ts":"2026-04-17T19:08:21.546597Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10528}
{"level":"info","ts":"2026-04-17T19:08:21.635395Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10528,"took":"85.495208ms","hash":2507035570,"current-db-size-bytes":57810944,"current-db-size":"58 MB","current-db-size-in-use-bytes":19828736,"current-db-size-in-use":"20 MB"}
{"level":"info","ts":"2026-04-17T19:08:21.635485Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2507035570,"revision":10528,"compact-revision":8105}