{"level":"warn","ts":"2026-07-01T07:18:43.23146Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T07:18:43.231555Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-01T07:18:43.231633Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T07:18:43.231649Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-07-01T07:18:43.231667Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-01T07:18:43.23226Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-07-01T07:18:43.232327Z","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.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-01T07:18:43.236198Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.576222ms"} {"level":"info","ts":"2026-07-01T07:18:43.242273Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-07-01T07:18:43.242357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T07:18:43.242383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-07-01T07:18:43.242403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T07:18:43.242417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-07-01T07:18:43.242468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-07-01T07:18:43.250641Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T07:18:43.251902Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T07:18:43.252898Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-01T07:18:43.255847Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T07:18:43.256743Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T07:18:43.256894Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T07:18:43.260862Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T07:18:43.260872Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T07:18:43.261982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-07-01T07:18:43.26218Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-07-01T07:18:43.263473Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-01T07:18:43.263509Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-07-01T07:18:43.264255Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-07-01T07:18:43.264496Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T07:18:43.26454Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T07:18:43.444593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T07:18:43.444819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T07:18:43.444894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-07-01T07:18:43.444949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-07-01T07:18:43.444985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-07-01T07:18:43.445024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-07-01T07:18:43.445111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-07-01T07:18:43.446908Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T07:18:43.447038Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T07:18:43.447093Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T07:18:43.447515Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T07:18:43.448414Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T07:18:43.447151Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:18:43.451983Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T07:18:43.452949Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-07-01T07:18:43.453358Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:18:43.453462Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:18:43.453495Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-07-01T07:18:46.600725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.974261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T07:18:46.600841Z","caller":"traceutil/trace.go:171","msg":"trace[81059119] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:112; }","duration":"212.191025ms","start":"2026-07-01T07:18:46.388629Z","end":"2026-07-01T07:18:46.60082Z","steps":["trace[81059119] 'range keys from in-memory index tree' (duration: 211.906451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:18:46.600837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.023993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T07:18:46.601003Z","caller":"traceutil/trace.go:171","msg":"trace[627531454] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:112; }","duration":"212.222604ms","start":"2026-07-01T07:18:46.388737Z","end":"2026-07-01T07:18:46.60096Z","steps":["trace[627531454] 'range keys from in-memory index tree' (duration: 211.920001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:18:46.600707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.524563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T07:18:46.601443Z","caller":"traceutil/trace.go:171","msg":"trace[2079253384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:112; }","duration":"209.304711ms","start":"2026-07-01T07:18:46.392117Z","end":"2026-07-01T07:18:46.601422Z","steps":["trace[2079253384] 'range keys from in-memory index tree' (duration: 208.430642ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:18:46.898572Z","caller":"traceutil/trace.go:171","msg":"trace[233829193] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"217.205072ms","start":"2026-07-01T07:18:46.68134Z","end":"2026-07-01T07:18:46.898545Z","steps":["trace[233829193] 'process raft request' (duration: 206.556771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:19:20.96804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.681513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:19:20.968353Z","caller":"traceutil/trace.go:171","msg":"trace[590546149] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"353.507967ms","start":"2026-07-01T07:19:20.614825Z","end":"2026-07-01T07:19:20.968333Z","steps":["trace[590546149] 'process raft request' (duration: 353.373116ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:19:20.968476Z","caller":"traceutil/trace.go:171","msg":"trace[1462681607] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"394.548828ms","start":"2026-07-01T07:19:20.573877Z","end":"2026-07-01T07:19:20.968426Z","steps":["trace[1462681607] 'process raft request' (duration: 62.614975ms)","trace[1462681607] 'compare' (duration: 329.316352ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:19:20.968532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:19:20.573856Z","time spent":"394.64568ms","remote":"127.0.0.1:37182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T07:19:20.968869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:19:20.614808Z","time spent":"353.592558ms","remote":"127.0.0.1:37182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:19:21.013535Z","caller":"traceutil/trace.go:171","msg":"trace[1489080971] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"262.923955ms","start":"2026-07-01T07:19:20.750591Z","end":"2026-07-01T07:19:21.013515Z","steps":["trace[1489080971] 'process raft request' (duration: 262.264479ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:19:25.59183Z","caller":"traceutil/trace.go:171","msg":"trace[283816725] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"106.256273ms","start":"2026-07-01T07:19:25.485545Z","end":"2026-07-01T07:19:25.591801Z","steps":["trace[283816725] 'read index received' (duration: 105.86528ms)","trace[283816725] 'applied index is now lower than readState.Index' (duration: 390.013µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:19:25.592005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.448574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:19:25.592038Z","caller":"traceutil/trace.go:171","msg":"trace[1617216258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"106.556955ms","start":"2026-07-01T07:19:25.485472Z","end":"2026-07-01T07:19:25.592029Z","steps":["trace[1617216258] 'agreement among raft nodes before linearized reading' (duration: 106.446114ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:19:25.592442Z","caller":"traceutil/trace.go:171","msg":"trace[1359545066] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"158.20553ms","start":"2026-07-01T07:19:25.434224Z","end":"2026-07-01T07:19:25.59243Z","steps":["trace[1359545066] 'process raft request' (duration: 157.441224ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:19:25.785017Z","caller":"traceutil/trace.go:171","msg":"trace[1880950097] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"151.115614ms","start":"2026-07-01T07:19:25.633846Z","end":"2026-07-01T07:19:25.784962Z","steps":["trace[1880950097] 'process raft request' (duration: 146.046244ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:19:26.285892Z","caller":"traceutil/trace.go:171","msg":"trace[1693398817] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"432.959ms","start":"2026-07-01T07:19:25.852921Z","end":"2026-07-01T07:19:26.28588Z","steps":["trace[1693398817] 'process raft request' (duration: 432.766159ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:19:26.285881Z","caller":"traceutil/trace.go:171","msg":"trace[177584434] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"275.032433ms","start":"2026-07-01T07:19:26.010826Z","end":"2026-07-01T07:19:26.285859Z","steps":["trace[177584434] 'read index received' (duration: 274.821491ms)","trace[177584434] 'applied index is now lower than readState.Index' (duration: 210.162µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:19:26.285971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:19:25.8529Z","time spent":"433.031261ms","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T07:19:26.286057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.462584ms","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-07-01T07:19:26.286097Z","caller":"traceutil/trace.go:171","msg":"trace[1919218827] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"181.547965ms","start":"2026-07-01T07:19:26.104538Z","end":"2026-07-01T07:19:26.286086Z","steps":["trace[1919218827] 'agreement among raft nodes before linearized reading' (duration: 181.439944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:19:26.286103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.263565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-01T07:19:26.286188Z","caller":"traceutil/trace.go:171","msg":"trace[179076107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"275.387665ms","start":"2026-07-01T07:19:26.010778Z","end":"2026-07-01T07:19:26.286165Z","steps":["trace[179076107] 'agreement among raft nodes before linearized reading' (duration: 275.178194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:19:41.698602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.850654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-lp7bz\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-01T07:19:41.698704Z","caller":"traceutil/trace.go:171","msg":"trace[1901057422] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-lp7bz; range_end:; response_count:1; response_revision:791; }","duration":"272.963824ms","start":"2026-07-01T07:19:41.425717Z","end":"2026-07-01T07:19:41.698681Z","steps":["trace[1901057422] 'range keys from in-memory index tree' (duration: 272.635942ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:56.424376Z","caller":"traceutil/trace.go:171","msg":"trace[1157201631] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"130.129067ms","start":"2026-07-01T07:20:56.294221Z","end":"2026-07-01T07:20:56.424351Z","steps":["trace[1157201631] 'process raft request' (duration: 129.963624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:57.019854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.011796ms","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-07-01T07:20:57.019936Z","caller":"traceutil/trace.go:171","msg":"trace[174818831] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1845; }","duration":"359.132448ms","start":"2026-07-01T07:20:56.660786Z","end":"2026-07-01T07:20:57.019918Z","steps":["trace[174818831] 'range keys from in-memory index tree' (duration: 358.847682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:57.019976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:56.660765Z","time spent":"359.20168ms","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-01T07:20:57.020476Z","caller":"traceutil/trace.go:171","msg":"trace[1227756669] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1917; }","duration":"239.36567ms","start":"2026-07-01T07:20:56.781088Z","end":"2026-07-01T07:20:57.020454Z","steps":["trace[1227756669] 'read index received' (duration: 212.064306ms)","trace[1227756669] 'applied index is now lower than readState.Index' (duration: 27.299814ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:20:57.02053Z","caller":"traceutil/trace.go:171","msg":"trace[712601329] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"317.205349ms","start":"2026-07-01T07:20:56.703291Z","end":"2026-07-01T07:20:57.020496Z","steps":["trace[712601329] 'process raft request' (duration: 289.839044ms)","trace[712601329] 'compare' (duration: 26.682153ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:20:57.020703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:56.703269Z","time spent":"317.330142ms","remote":"127.0.0.1:37408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:20:57.020714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.622854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-01bb707b-e455-4a03-bd5c-0d286a91f7f4\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-01T07:20:57.020767Z","caller":"traceutil/trace.go:171","msg":"trace[504963475] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-01bb707b-e455-4a03-bd5c-0d286a91f7f4; range_end:; response_count:1; response_revision:1846; }","duration":"239.727136ms","start":"2026-07-01T07:20:56.781024Z","end":"2026-07-01T07:20:57.020751Z","steps":["trace[504963475] 'agreement among raft nodes before linearized reading' (duration: 239.542273ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:57.09464Z","caller":"traceutil/trace.go:171","msg":"trace[1499601294] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"214.990169ms","start":"2026-07-01T07:20:56.87958Z","end":"2026-07-01T07:20:57.09457Z","steps":["trace[1499601294] 'process raft request' (duration: 214.846736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:57.094989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.866483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-01bb707b-e455-4a03-bd5c-0d286a91f7f4\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-07-01T07:20:57.094924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.879441ms","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-07-01T07:20:57.095061Z","caller":"traceutil/trace.go:171","msg":"trace[30931859] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-01bb707b-e455-4a03-bd5c-0d286a91f7f4; range_end:; response_count:1; response_revision:1847; }","duration":"221.968755ms","start":"2026-07-01T07:20:56.873074Z","end":"2026-07-01T07:20:57.095042Z","steps":["trace[30931859] 'agreement among raft nodes before linearized reading' (duration: 221.73191ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:57.095078Z","caller":"traceutil/trace.go:171","msg":"trace[1353766428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1847; }","duration":"265.062745ms","start":"2026-07-01T07:20:56.829992Z","end":"2026-07-01T07:20:57.095054Z","steps":["trace[1353766428] 'agreement among raft nodes before linearized reading' (duration: 264.822821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:57.095184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.358576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-01T07:20:57.095237Z","caller":"traceutil/trace.go:171","msg":"trace[547014922] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1847; }","duration":"271.452278ms","start":"2026-07-01T07:20:56.823769Z","end":"2026-07-01T07:20:57.095221Z","steps":["trace[547014922] 'agreement among raft nodes before linearized reading' (duration: 271.311866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:57.095229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.144377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-01T07:20:57.095309Z","caller":"traceutil/trace.go:171","msg":"trace[841459458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1847; }","duration":"265.263578ms","start":"2026-07-01T07:20:56.830026Z","end":"2026-07-01T07:20:57.09529Z","steps":["trace[841459458] 'agreement among raft nodes before linearized reading' (duration: 264.909862ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:57.273342Z","caller":"traceutil/trace.go:171","msg":"trace[1971209983] linearizableReadLoop","detail":"{readStateIndex:1920; appliedIndex:1919; }","duration":"178.538764ms","start":"2026-07-01T07:20:57.094779Z","end":"2026-07-01T07:20:57.273318Z","steps":["trace[1971209983] 'read index received' (duration: 178.420762ms)","trace[1971209983] 'applied index is now lower than readState.Index' (duration: 116.652µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:20:57.273561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.910875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-01T07:20:57.273601Z","caller":"traceutil/trace.go:171","msg":"trace[1894687873] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1848; }","duration":"209.008327ms","start":"2026-07-01T07:20:57.064583Z","end":"2026-07-01T07:20:57.273591Z","steps":["trace[1894687873] 'agreement among raft nodes before linearized reading' (duration: 208.827143ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:57.27382Z","caller":"traceutil/trace.go:171","msg":"trace[163940572] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"247.216778ms","start":"2026-07-01T07:20:57.026593Z","end":"2026-07-01T07:20:57.27381Z","steps":["trace[163940572] 'process raft request' (duration: 246.570886ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:57.291147Z","caller":"traceutil/trace.go:171","msg":"trace[1111330879] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"192.288367ms","start":"2026-07-01T07:20:57.098805Z","end":"2026-07-01T07:20:57.291093Z","steps":["trace[1111330879] 'process raft request' (duration: 191.835118ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:57.291306Z","caller":"traceutil/trace.go:171","msg":"trace[497567004] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"191.643606ms","start":"2026-07-01T07:20:57.099641Z","end":"2026-07-01T07:20:57.291285Z","steps":["trace[497567004] 'process raft request' (duration: 191.536214ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:57.291371Z","caller":"traceutil/trace.go:171","msg":"trace[1463960017] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"192.309638ms","start":"2026-07-01T07:20:57.099053Z","end":"2026-07-01T07:20:57.291363Z","steps":["trace[1463960017] 'process raft request' (duration: 191.83756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:57.621884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.053074ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:20:57.622027Z","caller":"traceutil/trace.go:171","msg":"trace[524814154] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1923; }","duration":"136.707034ms","start":"2026-07-01T07:20:57.485299Z","end":"2026-07-01T07:20:57.622006Z","steps":["trace[524814154] 'read index received' (duration: 25.394385ms)","trace[524814154] 'applied index is now lower than readState.Index' (duration: 111.311039ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:20:57.622281Z","caller":"traceutil/trace.go:171","msg":"trace[209781093] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"343.303477ms","start":"2026-07-01T07:20:57.278819Z","end":"2026-07-01T07:20:57.622123Z","steps":["trace[209781093] 'process raft request' (duration: 231.916826ms)","trace[209781093] 'compare' (duration: 110.616895ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:20:57.622313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.005981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:20:57.622466Z","caller":"traceutil/trace.go:171","msg":"trace[444856597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1852; }","duration":"137.202824ms","start":"2026-07-01T07:20:57.485248Z","end":"2026-07-01T07:20:57.622451Z","steps":["trace[444856597] 'agreement among raft nodes before linearized reading' (duration: 137.0247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:57.622393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:57.2788Z","time spent":"343.53339ms","remote":"127.0.0.1:37408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:20:57.622411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.262302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-01T07:20:57.622493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.642255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-01T07:20:57.62269Z","caller":"traceutil/trace.go:171","msg":"trace[980215906] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1852; }","duration":"118.547227ms","start":"2026-07-01T07:20:57.504115Z","end":"2026-07-01T07:20:57.622662Z","steps":["trace[980215906] 'agreement among raft nodes before linearized reading' (duration: 118.20638ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:57.622747Z","caller":"traceutil/trace.go:171","msg":"trace[478197381] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:1852; }","duration":"125.033622ms","start":"2026-07-01T07:20:57.497689Z","end":"2026-07-01T07:20:57.622722Z","steps":["trace[478197381] 'agreement among raft nodes before linearized reading' (duration: 124.548143ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:10.375198Z","caller":"traceutil/trace.go:171","msg":"trace[1158505575] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"102.926546ms","start":"2026-07-01T07:21:10.27225Z","end":"2026-07-01T07:21:10.375176Z","steps":["trace[1158505575] 'process raft request' (duration: 102.326386ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:31.333297Z","caller":"traceutil/trace.go:171","msg":"trace[742202983] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"107.096818ms","start":"2026-07-01T07:21:31.226168Z","end":"2026-07-01T07:21:31.333265Z","steps":["trace[742202983] 'process raft request' (duration: 106.846294ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:31.333524Z","caller":"traceutil/trace.go:171","msg":"trace[93172950] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"141.967184ms","start":"2026-07-01T07:21:31.19152Z","end":"2026-07-01T07:21:31.333487Z","steps":["trace[93172950] 'process raft request' (duration: 112.713983ms)","trace[93172950] 'compare' (duration: 28.628781ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:21:31.62114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.85069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-01T07:21:31.621532Z","caller":"traceutil/trace.go:171","msg":"trace[385572907] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2092; }","duration":"177.272697ms","start":"2026-07-01T07:21:31.444216Z","end":"2026-07-01T07:21:31.621489Z","steps":["trace[385572907] 'range keys from in-memory index tree' (duration: 176.649926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:31.621168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.624111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:21:31.621316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.895548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-01T07:21:31.621731Z","caller":"traceutil/trace.go:171","msg":"trace[1598572649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2092; }","duration":"121.343417ms","start":"2026-07-01T07:21:31.500372Z","end":"2026-07-01T07:21:31.621716Z","steps":["trace[1598572649] 'range keys from in-memory index tree' (duration: 120.759337ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:31.621737Z","caller":"traceutil/trace.go:171","msg":"trace[1832543646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2092; }","duration":"135.21313ms","start":"2026-07-01T07:21:31.486454Z","end":"2026-07-01T07:21:31.621667Z","steps":["trace[1832543646] 'range keys from in-memory index tree' (duration: 134.504159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:22:40.805005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.4238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:22:40.805249Z","caller":"traceutil/trace.go:171","msg":"trace[1200326093] transaction","detail":"{read_only:false; response_revision:2560; number_of_response:1; }","duration":"118.446306ms","start":"2026-07-01T07:22:40.686747Z","end":"2026-07-01T07:22:40.805194Z","steps":["trace[1200326093] 'process raft request' (duration: 118.402105ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:22:40.805328Z","caller":"traceutil/trace.go:171","msg":"trace[71171731] transaction","detail":"{read_only:false; response_revision:2559; number_of_response:1; }","duration":"118.550217ms","start":"2026-07-01T07:22:40.686744Z","end":"2026-07-01T07:22:40.805294Z","steps":["trace[71171731] 'compare' (duration: 110.327209ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:22:40.807606Z","caller":"traceutil/trace.go:171","msg":"trace[1894593573] transaction","detail":"{read_only:false; response_revision:2562; number_of_response:1; }","duration":"116.686226ms","start":"2026-07-01T07:22:40.690908Z","end":"2026-07-01T07:22:40.807594Z","steps":["trace[1894593573] 'process raft request' (duration: 116.645125ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:22:40.807783Z","caller":"traceutil/trace.go:171","msg":"trace[1285056005] transaction","detail":"{read_only:false; response_revision:2561; number_of_response:1; }","duration":"120.826524ms","start":"2026-07-01T07:22:40.686921Z","end":"2026-07-01T07:22:40.807747Z","steps":["trace[1285056005] 'process raft request' (duration: 120.536741ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:22:40.944545Z","caller":"traceutil/trace.go:171","msg":"trace[539178897] transaction","detail":"{read_only:false; response_revision:2565; number_of_response:1; }","duration":"106.08716ms","start":"2026-07-01T07:22:40.838403Z","end":"2026-07-01T07:22:40.94449Z","steps":["trace[539178897] 'process raft request' (duration: 67.939357ms)","trace[539178897] 'compare' (duration: 37.8306ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:22:41.099211Z","caller":"traceutil/trace.go:171","msg":"trace[1736354403] linearizableReadLoop","detail":"{readStateIndex:2663; appliedIndex:2662; }","duration":"107.21506ms","start":"2026-07-01T07:22:40.991952Z","end":"2026-07-01T07:22:41.099167Z","steps":["trace[1736354403] 'read index received' (duration: 87.192023ms)","trace[1736354403] 'applied index is now lower than readState.Index' (duration: 20.021427ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:22:41.09941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.445663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/auth-system/keycloak\" ","response":"range_response_count:1 size:905"} {"level":"info","ts":"2026-07-01T07:22:41.099557Z","caller":"traceutil/trace.go:171","msg":"trace[1792530644] range","detail":"{range_begin:/registry/serviceaccounts/auth-system/keycloak; range_end:; response_count:1; response_revision:2567; }","duration":"107.640716ms","start":"2026-07-01T07:22:40.991901Z","end":"2026-07-01T07:22:41.099541Z","steps":["trace[1792530644] 'agreement among raft nodes before linearized reading' (duration: 107.376203ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:22:41.099401Z","caller":"traceutil/trace.go:171","msg":"trace[662955896] transaction","detail":"{read_only:false; response_revision:2567; number_of_response:1; }","duration":"149.641255ms","start":"2026-07-01T07:22:40.949727Z","end":"2026-07-01T07:22:41.099368Z","steps":["trace[662955896] 'process raft request' (duration: 129.424235ms)","trace[662955896] 'compare' (duration: 19.883946ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:23:16.219217Z","caller":"traceutil/trace.go:171","msg":"trace[1623035073] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"103.166996ms","start":"2026-07-01T07:23:16.116024Z","end":"2026-07-01T07:23:16.219191Z","steps":["trace[1623035073] 'process raft request' (duration: 102.935184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:23:16.55772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.26333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:23:16.557895Z","caller":"traceutil/trace.go:171","msg":"trace[2132800748] transaction","detail":"{read_only:false; response_revision:2765; number_of_response:1; }","duration":"283.74918ms","start":"2026-07-01T07:23:16.274122Z","end":"2026-07-01T07:23:16.557872Z","steps":["trace[2132800748] 'process raft request' (duration: 113.246947ms)","trace[2132800748] 'compare' (duration: 170.140169ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:23:16.558181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.402013ms","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-07-01T07:23:16.55832Z","caller":"traceutil/trace.go:171","msg":"trace[1045713447] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2765; }","duration":"199.497395ms","start":"2026-07-01T07:23:16.358727Z","end":"2026-07-01T07:23:16.558225Z","steps":["trace[1045713447] 'agreement among raft nodes before linearized reading' (duration: 199.310633ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:23:16.55843Z","caller":"traceutil/trace.go:171","msg":"trace[2040214631] linearizableReadLoop","detail":"{readStateIndex:2869; appliedIndex:2868; }","duration":"199.030719ms","start":"2026-07-01T07:23:16.358778Z","end":"2026-07-01T07:23:16.557809Z","steps":["trace[2040214631] 'read index received' (duration: 28.572917ms)","trace[2040214631] 'applied index is now lower than readState.Index' (duration: 170.456742ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:23:16.726136Z","caller":"traceutil/trace.go:171","msg":"trace[1053839199] transaction","detail":"{read_only:false; response_revision:2766; number_of_response:1; }","duration":"163.89645ms","start":"2026-07-01T07:23:16.562207Z","end":"2026-07-01T07:23:16.726104Z","steps":["trace[1053839199] 'process raft request' (duration: 132.919636ms)","trace[1053839199] 'compare' (duration: 30.529959ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:26:55.520101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.826952ms","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-07-01T07:26:55.520193Z","caller":"traceutil/trace.go:171","msg":"trace[409177420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4688; }","duration":"179.965614ms","start":"2026-07-01T07:26:55.340207Z","end":"2026-07-01T07:26:55.520173Z","steps":["trace[409177420] 'range keys from in-memory index tree' (duration: 179.645798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:55.520312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.163222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-01T07:26:55.520402Z","caller":"traceutil/trace.go:171","msg":"trace[1802543921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4688; }","duration":"228.360716ms","start":"2026-07-01T07:26:55.29202Z","end":"2026-07-01T07:26:55.52038Z","steps":["trace[1802543921] 'range keys from in-memory index tree' (duration: 227.594042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:55.520898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.620827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-01T07:26:55.520935Z","caller":"traceutil/trace.go:171","msg":"trace[2094522931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4688; }","duration":"167.679399ms","start":"2026-07-01T07:26:55.353245Z","end":"2026-07-01T07:26:55.520925Z","steps":["trace[2094522931] 'range keys from in-memory index tree' (duration: 167.442904ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:55.658258Z","caller":"traceutil/trace.go:171","msg":"trace[1796586455] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"131.95006ms","start":"2026-07-01T07:26:55.526285Z","end":"2026-07-01T07:26:55.658235Z","steps":["trace[1796586455] 'process raft request' (duration: 123.504382ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:55.658478Z","caller":"traceutil/trace.go:171","msg":"trace[822654089] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"132.100592ms","start":"2026-07-01T07:26:55.526363Z","end":"2026-07-01T07:26:55.658463Z","steps":["trace[822654089] 'process raft request' (duration: 131.831348ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:55.658772Z","caller":"traceutil/trace.go:171","msg":"trace[490343137] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"131.493932ms","start":"2026-07-01T07:26:55.527266Z","end":"2026-07-01T07:26:55.65876Z","steps":["trace[490343137] 'process raft request' (duration: 131.164387ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:55.65902Z","caller":"traceutil/trace.go:171","msg":"trace[1103018625] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"127.851459ms","start":"2026-07-01T07:26:55.531151Z","end":"2026-07-01T07:26:55.659003Z","steps":["trace[1103018625] 'process raft request' (duration: 127.521793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:55.659066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.64848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2bc10700-527d-4ab1-abe3-ecda50d29da7\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-07-01T07:26:55.65911Z","caller":"traceutil/trace.go:171","msg":"trace[128770448] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2bc10700-527d-4ab1-abe3-ecda50d29da7; range_end:; response_count:1; response_revision:4693; }","duration":"133.720931ms","start":"2026-07-01T07:26:55.525376Z","end":"2026-07-01T07:26:55.659097Z","steps":["trace[128770448] 'agreement among raft nodes before linearized reading' (duration: 133.584979ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:07.849819Z","caller":"traceutil/trace.go:171","msg":"trace[1629738261] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"128.498982ms","start":"2026-07-01T07:27:07.7213Z","end":"2026-07-01T07:27:07.849799Z","steps":["trace[1629738261] 'process raft request' (duration: 128.309249ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:07.850042Z","caller":"traceutil/trace.go:171","msg":"trace[858306057] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"128.540433ms","start":"2026-07-01T07:27:07.721489Z","end":"2026-07-01T07:27:07.850029Z","steps":["trace[858306057] 'process raft request' (duration: 128.262808ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:07.850149Z","caller":"traceutil/trace.go:171","msg":"trace[730544291] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"128.476211ms","start":"2026-07-01T07:27:07.721632Z","end":"2026-07-01T07:27:07.850109Z","steps":["trace[730544291] 'process raft request' (duration: 128.340959ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:07.850288Z","caller":"traceutil/trace.go:171","msg":"trace[320190066] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"128.193137ms","start":"2026-07-01T07:27:07.72208Z","end":"2026-07-01T07:27:07.850273Z","steps":["trace[320190066] 'process raft request' (duration: 127.977643ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:07.850368Z","caller":"traceutil/trace.go:171","msg":"trace[739972072] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"128.266588ms","start":"2026-07-01T07:27:07.722077Z","end":"2026-07-01T07:27:07.850343Z","steps":["trace[739972072] 'process raft request' (duration: 128.121726ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:07.850488Z","caller":"traceutil/trace.go:171","msg":"trace[706256939] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"128.340939ms","start":"2026-07-01T07:27:07.722126Z","end":"2026-07-01T07:27:07.850467Z","steps":["trace[706256939] 'process raft request' (duration: 128.169346ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:44.154771Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2909} {"level":"warn","ts":"2026-07-01T07:28:44.314271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.408748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:28:44.314444Z","caller":"traceutil/trace.go:171","msg":"trace[325591949] transaction","detail":"{read_only:false; response_revision:5630; number_of_response:1; }","duration":"154.389391ms","start":"2026-07-01T07:28:44.160011Z","end":"2026-07-01T07:28:44.314401Z","steps":["trace[325591949] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/08db1feb.percona.com; req_size:506; } (duration: 147.254366ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:44.35529Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2909,"took":"199.077168ms","hash":1608904922,"current-db-size-bytes":54755328,"current-db-size":"55 MB","current-db-size-in-use-bytes":29704192,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-01T07:28:44.355344Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1608904922,"revision":2909,"compact-revision":-1} {"level":"info","ts":"2026-07-01T07:33:44.159419Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5629} {"level":"info","ts":"2026-07-01T07:33:44.235425Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5629,"took":"74.921633ms","hash":2073885396,"current-db-size-bytes":54755328,"current-db-size":"55 MB","current-db-size-in-use-bytes":18366464,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-01T07:33:44.235478Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2073885396,"revision":5629,"compact-revision":2909}