{"level":"warn","ts":"2026-05-05T11:14:26.288793Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T11:14:26.288875Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.125: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.125:2380","--initial-cluster=instance=https://199.19.213.125:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.125:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.125:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T11:14:26.288936Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T11:14:26.288948Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.125:2380"]} {"level":"info","ts":"2026-05-05T11:14:26.288965Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T11:14:26.289487Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.125:2379"]} {"level":"info","ts":"2026-05-05T11:14:26.289595Z","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.125:2380"],"listen-peer-urls":["https://199.19.213.125:2380"],"advertise-client-urls":["https://199.19.213.125:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.125:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.125:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T11:14:26.296093Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.312346ms"} {"level":"info","ts":"2026-05-05T11:14:26.302505Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b448fdce642f2755","cluster-id":"66f7e5dc9a72c5e3"} {"level":"info","ts":"2026-05-05T11:14:26.302592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T11:14:26.302613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became follower at term 0"} {"level":"info","ts":"2026-05-05T11:14:26.302636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b448fdce642f2755 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T11:14:26.302645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became follower at term 1"} {"level":"info","ts":"2026-05-05T11:14:26.302691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 switched to configuration voters=(12990912188036097877)"} {"level":"warn","ts":"2026-05-05T11:14:26.30753Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T11:14:26.308988Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T11:14:26.310195Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T11:14:26.312919Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b448fdce642f2755","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T11:14:26.313051Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b448fdce642f2755","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T11:14:26.313044Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T11:14:26.313269Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T11:14:26.313286Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T11:14:26.314077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 switched to configuration voters=(12990912188036097877)"} {"level":"info","ts":"2026-05-05T11:14:26.314193Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"66f7e5dc9a72c5e3","local-member-id":"b448fdce642f2755","added-peer-id":"b448fdce642f2755","added-peer-peer-urls":["https://199.19.213.125:2380"]} {"level":"info","ts":"2026-05-05T11:14:26.318353Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T11:14:26.318476Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.125:2380"} {"level":"info","ts":"2026-05-05T11:14:26.318506Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.125:2380"} {"level":"info","ts":"2026-05-05T11:14:26.318696Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b448fdce642f2755","initial-advertise-peer-urls":["https://199.19.213.125:2380"],"listen-peer-urls":["https://199.19.213.125:2380"],"advertise-client-urls":["https://199.19.213.125:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.125:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T11:14:26.318743Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T11:14:27.303506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T11:14:27.303647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T11:14:27.303769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 received MsgPreVoteResp from b448fdce642f2755 at term 1"} {"level":"info","ts":"2026-05-05T11:14:27.303846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became candidate at term 2"} {"level":"info","ts":"2026-05-05T11:14:27.303887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 received MsgVoteResp from b448fdce642f2755 at term 2"} {"level":"info","ts":"2026-05-05T11:14:27.303957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became leader at term 2"} {"level":"info","ts":"2026-05-05T11:14:27.304015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b448fdce642f2755 elected leader b448fdce642f2755 at term 2"} {"level":"info","ts":"2026-05-05T11:14:27.305155Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b448fdce642f2755","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.125:2379]}","request-path":"/0/members/b448fdce642f2755/attributes","cluster-id":"66f7e5dc9a72c5e3","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T11:14:27.305162Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T11:14:27.30521Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T11:14:27.305178Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:14:27.305625Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T11:14:27.305656Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T11:14:27.306461Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"66f7e5dc9a72c5e3","local-member-id":"b448fdce642f2755","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:14:27.30658Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:14:27.306617Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:14:27.308232Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T11:14:27.310943Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.125:2379"} {"level":"warn","ts":"2026-05-05T11:14:33.14079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.759943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:14:33.140935Z","caller":"traceutil/trace.go:171","msg":"trace[1633721881] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"254.746609ms","start":"2026-05-05T11:14:32.886171Z","end":"2026-05-05T11:14:33.140918Z","steps":["trace[1633721881] 'process raft request' (duration: 107.350515ms)","trace[1633721881] 'compare' (duration: 146.390632ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:14:33.197361Z","caller":"traceutil/trace.go:171","msg":"trace[1499066173] transaction","detail":"{read_only:false; number_of_response:0; response_revision:310; }","duration":"239.162965ms","start":"2026-05-05T11:14:32.958171Z","end":"2026-05-05T11:14:33.197334Z","steps":["trace[1499066173] 'process raft request' (duration: 239.111025ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:14:33.19742Z","caller":"traceutil/trace.go:171","msg":"trace[832887265] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"308.46794ms","start":"2026-05-05T11:14:32.888917Z","end":"2026-05-05T11:14:33.197385Z","steps":["trace[832887265] 'process raft request' (duration: 308.20119ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:14:33.197605Z","caller":"traceutil/trace.go:171","msg":"trace[1939677785] transaction","detail":"{read_only:false; number_of_response:0; response_revision:310; }","duration":"239.644185ms","start":"2026-05-05T11:14:32.95795Z","end":"2026-05-05T11:14:33.197594Z","steps":["trace[1939677785] 'process raft request' (duration: 239.301705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:14:33.197709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.118206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-05T11:14:33.197761Z","caller":"traceutil/trace.go:171","msg":"trace[927569043] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:315; }","duration":"266.039606ms","start":"2026-05-05T11:14:32.931554Z","end":"2026-05-05T11:14:33.197594Z","steps":["trace[927569043] 'read index received' (duration: 62.045379ms)","trace[927569043] 'applied index is now lower than readState.Index' (duration: 203.993327ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:14:33.197784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:14:32.888902Z","time spent":"308.59831ms","remote":"127.0.0.1:51710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:14:33.197792Z","caller":"traceutil/trace.go:171","msg":"trace[450021756] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:310; }","duration":"266.261846ms","start":"2026-05-05T11:14:32.931517Z","end":"2026-05-05T11:14:33.197779Z","steps":["trace[450021756] 'agreement among raft nodes before linearized reading' (duration: 266.080176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:14:33.197976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.808586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-05-05T11:14:33.198019Z","caller":"traceutil/trace.go:171","msg":"trace[1630125437] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:310; }","duration":"241.872666ms","start":"2026-05-05T11:14:32.956135Z","end":"2026-05-05T11:14:33.198007Z","steps":["trace[1630125437] 'agreement among raft nodes before linearized reading' (duration: 241.786786ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:14:33.198117Z","caller":"traceutil/trace.go:171","msg":"trace[597554157] transaction","detail":"{read_only:false; number_of_response:0; response_revision:310; }","duration":"239.830455ms","start":"2026-05-05T11:14:32.958278Z","end":"2026-05-05T11:14:33.198108Z","steps":["trace[597554157] 'process raft request' (duration: 239.032505ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:14:33.198206Z","caller":"traceutil/trace.go:171","msg":"trace[1448618229] transaction","detail":"{read_only:false; number_of_response:0; response_revision:310; }","duration":"239.041024ms","start":"2026-05-05T11:14:32.959159Z","end":"2026-05-05T11:14:33.1982Z","steps":["trace[1448618229] 'process raft request' (duration: 238.383644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:14:33.198206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.253926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T11:14:33.198318Z","caller":"traceutil/trace.go:171","msg":"trace[722396045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:310; }","duration":"216.377416ms","start":"2026-05-05T11:14:32.981928Z","end":"2026-05-05T11:14:33.198305Z","steps":["trace[722396045] 'agreement among raft nodes before linearized reading' (duration: 216.223046ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:14:33.369569Z","caller":"traceutil/trace.go:171","msg":"trace[1875237400] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:321; }","duration":"166.915997ms","start":"2026-05-05T11:14:33.202632Z","end":"2026-05-05T11:14:33.369548Z","steps":["trace[1875237400] 'read index received' (duration: 116.534008ms)","trace[1875237400] 'applied index is now lower than readState.Index' (duration: 50.380979ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:14:33.369778Z","caller":"traceutil/trace.go:171","msg":"trace[1482998017] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"167.947988ms","start":"2026-05-05T11:14:33.201818Z","end":"2026-05-05T11:14:33.369766Z","steps":["trace[1482998017] 'process raft request' (duration: 117.323129ms)","trace[1482998017] 'compare' (duration: 50.147259ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:14:33.370068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.403537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-05-05T11:14:33.37013Z","caller":"traceutil/trace.go:171","msg":"trace[542135190] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:311; }","duration":"167.517648ms","start":"2026-05-05T11:14:33.202599Z","end":"2026-05-05T11:14:33.370117Z","steps":["trace[542135190] 'agreement among raft nodes before linearized reading' (duration: 167.336647ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:14:33.373189Z","caller":"traceutil/trace.go:171","msg":"trace[743633192] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"169.27622ms","start":"2026-05-05T11:14:33.203885Z","end":"2026-05-05T11:14:33.373162Z","steps":["trace[743633192] 'process raft request' (duration: 169.11541ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:14:33.373285Z","caller":"traceutil/trace.go:171","msg":"trace[1014093918] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"128.599599ms","start":"2026-05-05T11:14:33.244652Z","end":"2026-05-05T11:14:33.373252Z","steps":["trace[1014093918] 'process raft request' (duration: 128.425719ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:08.519068Z","caller":"traceutil/trace.go:171","msg":"trace[1368986940] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"121.969687ms","start":"2026-05-05T11:15:08.397072Z","end":"2026-05-05T11:15:08.519042Z","steps":["trace[1368986940] 'process raft request' (duration: 121.315833ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:08.691331Z","caller":"traceutil/trace.go:171","msg":"trace[395923162] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"109.620761ms","start":"2026-05-05T11:15:08.581664Z","end":"2026-05-05T11:15:08.691284Z","steps":["trace[395923162] 'process raft request' (duration: 104.539693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:15:09.21157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.476535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-z2s6w\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-05T11:15:09.211659Z","caller":"traceutil/trace.go:171","msg":"trace[129784273] range","detail":"{range_begin:/registry/pods/kube-system/cilium-z2s6w; range_end:; response_count:1; response_revision:628; }","duration":"169.620837ms","start":"2026-05-05T11:15:09.042015Z","end":"2026-05-05T11:15:09.211636Z","steps":["trace[129784273] 'range keys from in-memory index tree' (duration: 169.229515ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:09.221065Z","caller":"traceutil/trace.go:171","msg":"trace[1920269372] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"130.397524ms","start":"2026-05-05T11:15:09.090643Z","end":"2026-05-05T11:15:09.22104Z","steps":["trace[1920269372] 'process raft request' (duration: 129.117352ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:24.816648Z","caller":"traceutil/trace.go:171","msg":"trace[276377976] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"107.639856ms","start":"2026-05-05T11:15:24.708985Z","end":"2026-05-05T11:15:24.816624Z","steps":["trace[276377976] 'process raft request' (duration: 107.513619ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:15:25.396031Z","caller":"traceutil/trace.go:171","msg":"trace[2081687783] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"257.948569ms","start":"2026-05-05T11:15:25.138049Z","end":"2026-05-05T11:15:25.395998Z","steps":["trace[2081687783] 'process raft request' (duration: 257.659944ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:16:37.597063Z","caller":"traceutil/trace.go:171","msg":"trace[577941789] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1911; }","duration":"289.392719ms","start":"2026-05-05T11:16:37.307645Z","end":"2026-05-05T11:16:37.597037Z","steps":["trace[577941789] 'read index received' (duration: 289.182495ms)","trace[577941789] 'applied index is now lower than readState.Index' (duration: 209.064µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:16:37.597237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.681455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:16:37.597308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.796465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f98951b5-7e47-46c7-b3da-adbcaeefe1c7\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-05T11:16:37.597307Z","caller":"traceutil/trace.go:171","msg":"trace[1955803183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1842; }","duration":"252.785396ms","start":"2026-05-05T11:16:37.344504Z","end":"2026-05-05T11:16:37.597289Z","steps":["trace[1955803183] 'agreement among raft nodes before linearized reading' (duration: 252.672194ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:16:37.59734Z","caller":"traceutil/trace.go:171","msg":"trace[166221776] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f98951b5-7e47-46c7-b3da-adbcaeefe1c7; range_end:; response_count:1; response_revision:1842; }","duration":"110.866885ms","start":"2026-05-05T11:16:37.486465Z","end":"2026-05-05T11:16:37.597331Z","steps":["trace[166221776] 'agreement among raft nodes before linearized reading' (duration: 110.728173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:37.597308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.651354ms","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-05-05T11:16:37.597388Z","caller":"traceutil/trace.go:171","msg":"trace[1245218351] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1842; }","duration":"289.778715ms","start":"2026-05-05T11:16:37.307589Z","end":"2026-05-05T11:16:37.597368Z","steps":["trace[1245218351] 'agreement among raft nodes before linearized reading' (duration: 289.529701ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:16:37.59772Z","caller":"traceutil/trace.go:171","msg":"trace[7172323] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"322.904922ms","start":"2026-05-05T11:16:37.274797Z","end":"2026-05-05T11:16:37.597702Z","steps":["trace[7172323] 'process raft request' (duration: 322.081399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:16:37.597812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:16:37.274782Z","time spent":"322.971964ms","remote":"127.0.0.1:51710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:16:38.006604Z","caller":"traceutil/trace.go:171","msg":"trace[1802753658] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"170.324101ms","start":"2026-05-05T11:16:37.836248Z","end":"2026-05-05T11:16:38.006572Z","steps":["trace[1802753658] 'process raft request' (duration: 114.189901ms)","trace[1802753658] 'compare' (duration: 56.002848ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:17:12.333861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.885887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-05T11:17:12.333923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.734447ms","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-05-05T11:17:12.334016Z","caller":"traceutil/trace.go:171","msg":"trace[1918782961] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2086; }","duration":"150.889765ms","start":"2026-05-05T11:17:12.183108Z","end":"2026-05-05T11:17:12.333998Z","steps":["trace[1918782961] 'range keys from in-memory index tree' (duration: 150.561799ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:17:12.334015Z","caller":"traceutil/trace.go:171","msg":"trace[1680631246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2086; }","duration":"148.070516ms","start":"2026-05-05T11:17:12.185918Z","end":"2026-05-05T11:17:12.333989Z","steps":["trace[1680631246] 'range keys from in-memory index tree' (duration: 147.634865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:12.334054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.122108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T11:17:12.334133Z","caller":"traceutil/trace.go:171","msg":"trace[191459209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2086; }","duration":"148.249394ms","start":"2026-05-05T11:17:12.185866Z","end":"2026-05-05T11:17:12.334115Z","steps":["trace[191459209] 'range keys from in-memory index tree' (duration: 147.872886ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:17:12.591853Z","caller":"traceutil/trace.go:171","msg":"trace[1799722748] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"251.842124ms","start":"2026-05-05T11:17:12.339969Z","end":"2026-05-05T11:17:12.591811Z","steps":["trace[1799722748] 'process raft request' (duration: 237.056923ms)","trace[1799722748] 'compare' (duration: 14.000542ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:17:12.592131Z","caller":"traceutil/trace.go:171","msg":"trace[1549517524] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"252.086025ms","start":"2026-05-05T11:17:12.340037Z","end":"2026-05-05T11:17:12.592123Z","steps":["trace[1549517524] 'process raft request' (duration: 251.717667ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:17:12.592264Z","caller":"traceutil/trace.go:171","msg":"trace[861313675] linearizableReadLoop","detail":"{readStateIndex:2166; appliedIndex:2163; }","duration":"249.061906ms","start":"2026-05-05T11:17:12.343186Z","end":"2026-05-05T11:17:12.592248Z","steps":["trace[861313675] 'read index received' (duration: 12.7406ms)","trace[861313675] 'applied index is now lower than readState.Index' (duration: 236.320486ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:17:12.592352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.15369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:17:12.592372Z","caller":"traceutil/trace.go:171","msg":"trace[732493003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2090; }","duration":"249.205603ms","start":"2026-05-05T11:17:12.343161Z","end":"2026-05-05T11:17:12.592367Z","steps":["trace[732493003] 'agreement among raft nodes before linearized reading' (duration: 249.14989ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:17:12.592408Z","caller":"traceutil/trace.go:171","msg":"trace[737561056] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"215.536959ms","start":"2026-05-05T11:17:12.376861Z","end":"2026-05-05T11:17:12.592398Z","steps":["trace[737561056] 'process raft request' (duration: 215.13866ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:16.486269Z","caller":"traceutil/trace.go:171","msg":"trace[1089702731] transaction","detail":"{read_only:false; response_revision:2529; number_of_response:1; }","duration":"110.603043ms","start":"2026-05-05T11:18:16.375433Z","end":"2026-05-05T11:18:16.486036Z","steps":["trace[1089702731] 'process raft request' (duration: 110.458139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:49.348391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.823981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:18:49.348486Z","caller":"traceutil/trace.go:171","msg":"trace[1293225494] linearizableReadLoop","detail":"{readStateIndex:2811; appliedIndex:2810; }","duration":"238.236592ms","start":"2026-05-05T11:18:49.110234Z","end":"2026-05-05T11:18:49.348471Z","steps":["trace[1293225494] 'read index received' (duration: 113.193795ms)","trace[1293225494] 'applied index is now lower than readState.Index' (duration: 125.041747ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:18:49.348571Z","caller":"traceutil/trace.go:171","msg":"trace[1640127587] transaction","detail":"{read_only:false; response_revision:2712; number_of_response:1; }","duration":"249.797095ms","start":"2026-05-05T11:18:49.098763Z","end":"2026-05-05T11:18:49.34856Z","steps":["trace[1640127587] 'process raft request' (duration: 124.739548ms)","trace[1640127587] 'compare' (duration: 124.689828ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:18:49.348716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.483047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T11:18:49.348763Z","caller":"traceutil/trace.go:171","msg":"trace[580398447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2712; }","duration":"238.553359ms","start":"2026-05-05T11:18:49.110203Z","end":"2026-05-05T11:18:49.348756Z","steps":["trace[580398447] 'agreement among raft nodes before linearized reading' (duration: 238.446537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:49.348895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.033985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-05T11:18:49.349205Z","caller":"traceutil/trace.go:171","msg":"trace[927720342] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2712; }","duration":"221.389733ms","start":"2026-05-05T11:18:49.127801Z","end":"2026-05-05T11:18:49.349191Z","steps":["trace[927720342] 'agreement among raft nodes before linearized reading' (duration: 220.80086ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:22:43.344169Z","caller":"traceutil/trace.go:171","msg":"trace[386055123] linearizableReadLoop","detail":"{readStateIndex:4729; appliedIndex:4727; }","duration":"129.207371ms","start":"2026-05-05T11:22:43.214927Z","end":"2026-05-05T11:22:43.344135Z","steps":["trace[386055123] 'read index received' (duration: 60.801239ms)","trace[386055123] 'applied index is now lower than readState.Index' (duration: 68.404662ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:22:43.344253Z","caller":"traceutil/trace.go:171","msg":"trace[312688679] transaction","detail":"{read_only:false; response_revision:4556; number_of_response:1; }","duration":"158.6719ms","start":"2026-05-05T11:22:43.185544Z","end":"2026-05-05T11:22:43.344216Z","steps":["trace[312688679] 'process raft request' (duration: 158.427143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:22:43.344901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.92448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:22:43.345004Z","caller":"traceutil/trace.go:171","msg":"trace[1752691796] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:4556; }","duration":"130.061514ms","start":"2026-05-05T11:22:43.21492Z","end":"2026-05-05T11:22:43.344982Z","steps":["trace[1752691796] 'agreement among raft nodes before linearized reading' (duration: 129.392095ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:22:47.844699Z","caller":"traceutil/trace.go:171","msg":"trace[1257049003] transaction","detail":"{read_only:false; response_revision:4647; number_of_response:1; }","duration":"100.214157ms","start":"2026-05-05T11:22:47.74445Z","end":"2026-05-05T11:22:47.844664Z","steps":["trace[1257049003] 'process raft request' (duration: 100.033312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:22:52.2908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.218089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:22:52.29094Z","caller":"traceutil/trace.go:171","msg":"trace[486713232] linearizableReadLoop","detail":"{readStateIndex:4923; appliedIndex:4922; }","duration":"220.621833ms","start":"2026-05-05T11:22:52.070297Z","end":"2026-05-05T11:22:52.290919Z","steps":["trace[486713232] 'read index received' (duration: 27.201468ms)","trace[486713232] 'applied index is now lower than readState.Index' (duration: 193.419115ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:22:52.291059Z","caller":"traceutil/trace.go:171","msg":"trace[1702808576] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"281.088865ms","start":"2026-05-05T11:22:52.009927Z","end":"2026-05-05T11:22:52.291015Z","steps":["trace[1702808576] 'process raft request' (duration: 87.537777ms)","trace[1702808576] 'compare' (duration: 193.058265ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:22:52.291151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.864919ms","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":"info","ts":"2026-05-05T11:22:52.291181Z","caller":"traceutil/trace.go:171","msg":"trace[736674484] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4744; }","duration":"220.946001ms","start":"2026-05-05T11:22:52.070222Z","end":"2026-05-05T11:22:52.291168Z","steps":["trace[736674484] 'agreement among raft nodes before linearized reading' (duration: 220.786277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:22:52.291607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.516223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ec645275-f343-4761-90ba-22a3eca61a9b\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-05T11:22:52.29167Z","caller":"traceutil/trace.go:171","msg":"trace[162674111] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ec645275-f343-4761-90ba-22a3eca61a9b; range_end:; response_count:1; response_revision:4744; }","duration":"184.702047ms","start":"2026-05-05T11:22:52.10695Z","end":"2026-05-05T11:22:52.291652Z","steps":["trace[162674111] 'agreement among raft nodes before linearized reading' (duration: 184.382458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:22:52.291763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.629831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T11:22:52.291849Z","caller":"traceutil/trace.go:171","msg":"trace[1240451973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4744; }","duration":"168.752115ms","start":"2026-05-05T11:22:52.123078Z","end":"2026-05-05T11:22:52.29183Z","steps":["trace[1240451973] 'agreement among raft nodes before linearized reading' (duration: 168.488558ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:23:05.895596Z","caller":"traceutil/trace.go:171","msg":"trace[230321115] linearizableReadLoop","detail":"{readStateIndex:5110; appliedIndex:5109; }","duration":"282.210602ms","start":"2026-05-05T11:23:05.613357Z","end":"2026-05-05T11:23:05.895568Z","steps":["trace[230321115] 'read index received' (duration: 281.888644ms)","trace[230321115] 'applied index is now lower than readState.Index' (duration: 320.628µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:23:05.89566Z","caller":"traceutil/trace.go:171","msg":"trace[446321212] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"344.868012ms","start":"2026-05-05T11:23:05.550767Z","end":"2026-05-05T11:23:05.895635Z","steps":["trace[446321212] 'process raft request' (duration: 344.580425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:23:05.895883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.51218ms","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-05-05T11:23:05.895947Z","caller":"traceutil/trace.go:171","msg":"trace[2107345682] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4925; }","duration":"282.621943ms","start":"2026-05-05T11:23:05.613309Z","end":"2026-05-05T11:23:05.895931Z","steps":["trace[2107345682] 'agreement among raft nodes before linearized reading' (duration: 282.415247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:23:05.896175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.736014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"warn","ts":"2026-05-05T11:23:05.895898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:23:05.550741Z","time spent":"344.979415ms","remote":"127.0.0.1:51618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:23:05.896357Z","caller":"traceutil/trace.go:171","msg":"trace[188387800] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4925; }","duration":"209.905988ms","start":"2026-05-05T11:23:05.686391Z","end":"2026-05-05T11:23:05.896297Z","steps":["trace[188387800] 'agreement among raft nodes before linearized reading' (duration: 209.463227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:23:05.896175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.604225ms","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-05-05T11:23:05.896746Z","caller":"traceutil/trace.go:171","msg":"trace[1877643861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4925; }","duration":"203.201441ms","start":"2026-05-05T11:23:05.693491Z","end":"2026-05-05T11:23:05.896692Z","steps":["trace[1877643861] 'agreement among raft nodes before linearized reading' (duration: 202.387119ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:23:40.689435Z","caller":"traceutil/trace.go:171","msg":"trace[686848086] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"110.452884ms","start":"2026-05-05T11:23:40.578951Z","end":"2026-05-05T11:23:40.689404Z","steps":["trace[686848086] 'process raft request' (duration: 110.171358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:23:40.715913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.064639ms","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-05-05T11:23:40.716037Z","caller":"traceutil/trace.go:171","msg":"trace[968661309] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5255; }","duration":"100.219713ms","start":"2026-05-05T11:23:40.615792Z","end":"2026-05-05T11:23:40.716012Z","steps":["trace[968661309] 'agreement among raft nodes before linearized reading' (duration: 99.885055ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:24:27.330757Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2911} {"level":"info","ts":"2026-05-05T11:24:27.411975Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2911,"took":"79.221356ms","hash":2996163974,"current-db-size-bytes":55037952,"current-db-size":"55 MB","current-db-size-in-use-bytes":30089216,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-05T11:24:27.412061Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2996163974,"revision":2911,"compact-revision":-1} {"level":"info","ts":"2026-05-05T11:29:27.336368Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5630} {"level":"info","ts":"2026-05-05T11:29:27.407397Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5630,"took":"69.348867ms","hash":632562663,"current-db-size-bytes":55037952,"current-db-size":"55 MB","current-db-size-in-use-bytes":18440192,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-05T11:29:27.40745Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":632562663,"revision":5630,"compact-revision":2911} {"level":"info","ts":"2026-05-05T11:30:19.705537Z","caller":"traceutil/trace.go:171","msg":"trace[1180476991] linearizableReadLoop","detail":"{readStateIndex:7978; appliedIndex:7977; }","duration":"100.005064ms","start":"2026-05-05T11:30:19.605488Z","end":"2026-05-05T11:30:19.705493Z","steps":["trace[1180476991] 'read index received' (duration: 36.411292ms)","trace[1180476991] 'applied index is now lower than readState.Index' (duration: 63.591931ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:30:19.705624Z","caller":"traceutil/trace.go:171","msg":"trace[1483057166] transaction","detail":"{read_only:false; response_revision:7694; number_of_response:1; }","duration":"128.76653ms","start":"2026-05-05T11:30:19.576824Z","end":"2026-05-05T11:30:19.70559Z","steps":["trace[1483057166] 'process raft request' (duration: 65.179619ms)","trace[1483057166] 'compare' (duration: 63.304851ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:30:19.705765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.263682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T11:30:19.705849Z","caller":"traceutil/trace.go:171","msg":"trace[2137392564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7694; }","duration":"100.391367ms","start":"2026-05-05T11:30:19.605441Z","end":"2026-05-05T11:30:19.705832Z","steps":["trace[2137392564] 'agreement among raft nodes before linearized reading' (duration: 100.225701ms)"],"step_count":1}