{"level":"warn","ts":"2026-04-20T04:52:39.080362Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T04:52:39.080498Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-20T04:52:39.080589Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T04:52:39.080604Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-04-20T04:52:39.08063Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T04:52:39.081492Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-04-20T04:52:39.08158Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-20T04:52:39.086422Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.568484ms"} {"level":"info","ts":"2026-04-20T04:52:39.094211Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-04-20T04:52:39.094286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T04:52:39.094311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-04-20T04:52:39.094321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T04:52:39.094337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-04-20T04:52:39.094378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-04-20T04:52:39.101856Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T04:52:39.10398Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T04:52:39.105412Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-20T04:52:39.10843Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T04:52:39.108687Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T04:52:39.108757Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T04:52:39.109475Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T04:52:39.10953Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T04:52:39.110051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-04-20T04:52:39.111099Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-04-20T04:52:39.112506Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T04:52:39.112622Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-04-20T04:52:39.11269Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-04-20T04:52:39.112732Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T04:52:39.11277Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T04:52:40.095145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T04:52:40.095247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T04:52:40.095272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-04-20T04:52:40.095294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-04-20T04:52:40.095305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-04-20T04:52:40.095321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-04-20T04:52:40.095343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-04-20T04:52:40.096652Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T04:52:40.096722Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T04:52:40.096702Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:52:40.096774Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T04:52:40.097094Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T04:52:40.097135Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T04:52:40.097748Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:52:40.09787Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:52:40.097902Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:52:40.100103Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-04-20T04:52:40.10048Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T04:53:07.816988Z","caller":"traceutil/trace.go:171","msg":"trace[600360389] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"179.625653ms","start":"2026-04-20T04:53:07.637326Z","end":"2026-04-20T04:53:07.816951Z","steps":["trace[600360389] 'process raft request' (duration: 179.385398ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:07.821817Z","caller":"traceutil/trace.go:171","msg":"trace[1729218091] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"183.947193ms","start":"2026-04-20T04:53:07.637846Z","end":"2026-04-20T04:53:07.821793Z","steps":["trace[1729218091] 'process raft request' (duration: 183.083907ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:15.691071Z","caller":"traceutil/trace.go:171","msg":"trace[792589650] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"129.012401ms","start":"2026-04-20T04:53:15.562028Z","end":"2026-04-20T04:53:15.691041Z","steps":["trace[792589650] 'process raft request' (duration: 128.455911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:16.119019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.429972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:53:16.119164Z","caller":"traceutil/trace.go:171","msg":"trace[448909755] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"393.951501ms","start":"2026-04-20T04:53:15.725194Z","end":"2026-04-20T04:53:16.119145Z","steps":["trace[448909755] 'process raft request' (duration: 237.667693ms)","trace[448909755] 'compare' (duration: 155.245769ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:16.119239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:15.725127Z","time spent":"394.078803ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:53:16.129926Z","caller":"traceutil/trace.go:171","msg":"trace[813211283] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:635; }","duration":"258.168903ms","start":"2026-04-20T04:53:15.871735Z","end":"2026-04-20T04:53:16.129904Z","steps":["trace[813211283] 'read index received' (duration: 91.142625ms)","trace[813211283] 'applied index is now lower than readState.Index' (duration: 167.025348ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:16.129953Z","caller":"traceutil/trace.go:171","msg":"trace[1088015584] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"384.949123ms","start":"2026-04-20T04:53:15.744975Z","end":"2026-04-20T04:53:16.129924Z","steps":["trace[1088015584] 'process raft request' (duration: 384.82193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:16.130195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:15.744946Z","time spent":"385.118906ms","remote":"127.0.0.1:39718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:53:16.130218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.47329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T04:53:16.130253Z","caller":"traceutil/trace.go:171","msg":"trace[1965445877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"258.570641ms","start":"2026-04-20T04:53:15.871671Z","end":"2026-04-20T04:53:16.130242Z","steps":["trace[1965445877] 'agreement among raft nodes before linearized reading' (duration: 258.371938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:16.130276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.767424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T04:53:16.130353Z","caller":"traceutil/trace.go:171","msg":"trace[793066638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"252.912347ms","start":"2026-04-20T04:53:15.877423Z","end":"2026-04-20T04:53:16.130335Z","steps":["trace[793066638] 'agreement among raft nodes before linearized reading' (duration: 252.654572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:16.13056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.011258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T04:53:16.130669Z","caller":"traceutil/trace.go:171","msg":"trace[1838154809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"253.196101ms","start":"2026-04-20T04:53:15.877447Z","end":"2026-04-20T04:53:16.130643Z","steps":["trace[1838154809] 'agreement among raft nodes before linearized reading' (duration: 252.658682ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:16.810406Z","caller":"traceutil/trace.go:171","msg":"trace[1150057878] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"651.925621ms","start":"2026-04-20T04:53:16.158187Z","end":"2026-04-20T04:53:16.810113Z","steps":["trace[1150057878] 'process raft request' (duration: 618.977808ms)","trace[1150057878] 'compare' (duration: 32.547956ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:16.810616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:16.158167Z","time spent":"652.361429ms","remote":"127.0.0.1:39378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:53:16.814241Z","caller":"traceutil/trace.go:171","msg":"trace[1429111776] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"287.470529ms","start":"2026-04-20T04:53:16.526743Z","end":"2026-04-20T04:53:16.814213Z","steps":["trace[1429111776] 'process raft request' (duration: 286.928949ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:17.264412Z","caller":"traceutil/trace.go:171","msg":"trace[2130511209] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"109.480437ms","start":"2026-04-20T04:53:17.154908Z","end":"2026-04-20T04:53:17.264388Z","steps":["trace[2130511209] 'process raft request' (duration: 108.650321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:46.781812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.449566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8bc2175c-0536-4fc9-8732-2f11de7073ea\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T04:54:46.781921Z","caller":"traceutil/trace.go:171","msg":"trace[770323593] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8bc2175c-0536-4fc9-8732-2f11de7073ea; range_end:; response_count:1; response_revision:1837; }","duration":"137.602139ms","start":"2026-04-20T04:54:46.644299Z","end":"2026-04-20T04:54:46.781901Z","steps":["trace[770323593] 'range keys from in-memory index tree' (duration: 137.280772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:46.781802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.928843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T04:54:46.781953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.583737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T04:54:46.782035Z","caller":"traceutil/trace.go:171","msg":"trace[274533022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1837; }","duration":"124.666628ms","start":"2026-04-20T04:54:46.657341Z","end":"2026-04-20T04:54:46.782007Z","steps":["trace[274533022] 'range keys from in-memory index tree' (duration: 124.463864ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:46.782036Z","caller":"traceutil/trace.go:171","msg":"trace[788681630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1837; }","duration":"139.210358ms","start":"2026-04-20T04:54:46.642785Z","end":"2026-04-20T04:54:46.781995Z","steps":["trace[788681630] 'range keys from in-memory index tree' (duration: 138.667998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:46.782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.95252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-20T04:54:46.782129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.140041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8bc2175c-0536-4fc9-8732-2f11de7073ea\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T04:54:46.782167Z","caller":"traceutil/trace.go:171","msg":"trace[906055487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1837; }","duration":"120.255536ms","start":"2026-04-20T04:54:46.66188Z","end":"2026-04-20T04:54:46.782135Z","steps":["trace[906055487] 'range keys from in-memory index tree' (duration: 119.682976ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:46.782222Z","caller":"traceutil/trace.go:171","msg":"trace[1495982508] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8bc2175c-0536-4fc9-8732-2f11de7073ea; range_end:; response_count:1; response_revision:1837; }","duration":"214.330344ms","start":"2026-04-20T04:54:46.567869Z","end":"2026-04-20T04:54:46.7822Z","steps":["trace[1495982508] 'range keys from in-memory index tree' (duration: 213.810845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:47.2943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.440003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:54:47.294634Z","caller":"traceutil/trace.go:171","msg":"trace[1678404001] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"446.603972ms","start":"2026-04-20T04:54:46.847995Z","end":"2026-04-20T04:54:47.294599Z","steps":["trace[1678404001] 'process raft request' (duration: 94.720401ms)","trace[1678404001] 'compare' (duration: 351.170848ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:47.294644Z","caller":"traceutil/trace.go:171","msg":"trace[784038267] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"344.841351ms","start":"2026-04-20T04:54:46.94978Z","end":"2026-04-20T04:54:47.294621Z","steps":["trace[784038267] 'process raft request' (duration: 344.671948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:47.294761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:46.847976Z","time spent":"446.715515ms","remote":"127.0.0.1:39378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10970,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:54:47.294858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:46.949754Z","time spent":"344.949162ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:54:52.940547Z","caller":"traceutil/trace.go:171","msg":"trace[647795065] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"108.400565ms","start":"2026-04-20T04:54:52.832125Z","end":"2026-04-20T04:54:52.940525Z","steps":["trace[647795065] 'process raft request' (duration: 107.982548ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:55:01.621092Z","caller":"traceutil/trace.go:171","msg":"trace[701100104] linearizableReadLoop","detail":"{readStateIndex:2040; appliedIndex:2039; }","duration":"181.487283ms","start":"2026-04-20T04:55:01.439579Z","end":"2026-04-20T04:55:01.621066Z","steps":["trace[701100104] 'read index received' (duration: 180.503665ms)","trace[701100104] 'applied index is now lower than readState.Index' (duration: 982.528µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:55:01.621158Z","caller":"traceutil/trace.go:171","msg":"trace[1212339250] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"253.025684ms","start":"2026-04-20T04:55:01.368091Z","end":"2026-04-20T04:55:01.621116Z","steps":["trace[1212339250] 'process raft request' (duration: 251.983384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:55:01.621422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.812329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-20T04:55:01.621521Z","caller":"traceutil/trace.go:171","msg":"trace[179049132] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1967; }","duration":"181.967143ms","start":"2026-04-20T04:55:01.439531Z","end":"2026-04-20T04:55:01.621498Z","steps":["trace[179049132] 'agreement among raft nodes before linearized reading' (duration: 181.623817ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:55:01.885912Z","caller":"traceutil/trace.go:171","msg":"trace[1196371461] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"106.586141ms","start":"2026-04-20T04:55:01.779302Z","end":"2026-04-20T04:55:01.885888Z","steps":["trace[1196371461] 'process raft request' (duration: 87.077139ms)","trace[1196371461] 'compare' (duration: 19.322909ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:55:25.452752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.606381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-20T04:55:25.452839Z","caller":"traceutil/trace.go:171","msg":"trace[1136009258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2104; }","duration":"241.745194ms","start":"2026-04-20T04:55:25.211075Z","end":"2026-04-20T04:55:25.45282Z","steps":["trace[1136009258] 'range keys from in-memory index tree' (duration: 241.396068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:55:25.453174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.255134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T04:55:25.453328Z","caller":"traceutil/trace.go:171","msg":"trace[85103562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2104; }","duration":"242.504579ms","start":"2026-04-20T04:55:25.210795Z","end":"2026-04-20T04:55:25.453299Z","steps":["trace[85103562] 'range keys from in-memory index tree' (duration: 241.996969ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:55:36.21211Z","caller":"traceutil/trace.go:171","msg":"trace[1759433918] linearizableReadLoop","detail":"{readStateIndex:2243; appliedIndex:2240; }","duration":"167.072111ms","start":"2026-04-20T04:55:36.045Z","end":"2026-04-20T04:55:36.212072Z","steps":["trace[1759433918] 'read index received' (duration: 41.579199ms)","trace[1759433918] 'applied index is now lower than readState.Index' (duration: 125.491412ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:55:36.212817Z","caller":"traceutil/trace.go:171","msg":"trace[247614385] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"206.801407ms","start":"2026-04-20T04:55:36.005965Z","end":"2026-04-20T04:55:36.212766Z","steps":["trace[247614385] 'process raft request' (duration: 205.199807ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:55:36.213236Z","caller":"traceutil/trace.go:171","msg":"trace[1253456550] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"180.827826ms","start":"2026-04-20T04:55:36.032388Z","end":"2026-04-20T04:55:36.213215Z","steps":["trace[1253456550] 'process raft request' (duration: 179.629284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:55:36.213792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.755983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T04:55:36.2139Z","caller":"traceutil/trace.go:171","msg":"trace[1856344721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2163; }","duration":"168.929885ms","start":"2026-04-20T04:55:36.044949Z","end":"2026-04-20T04:55:36.213879Z","steps":["trace[1856344721] 'agreement among raft nodes before linearized reading' (duration: 168.65329ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:55:36.373931Z","caller":"traceutil/trace.go:171","msg":"trace[2107392386] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"154.926376ms","start":"2026-04-20T04:55:36.218945Z","end":"2026-04-20T04:55:36.373872Z","steps":["trace[2107392386] 'process raft request' (duration: 110.111367ms)","trace[2107392386] 'compare' (duration: 44.596535ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:55:36.978506Z","caller":"traceutil/trace.go:171","msg":"trace[2022914631] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"115.67166ms","start":"2026-04-20T04:55:36.862812Z","end":"2026-04-20T04:55:36.978483Z","steps":["trace[2022914631] 'process raft request' (duration: 115.556458ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:55:43.370707Z","caller":"traceutil/trace.go:171","msg":"trace[1366299553] linearizableReadLoop","detail":"{readStateIndex:2280; appliedIndex:2278; }","duration":"107.560419ms","start":"2026-04-20T04:55:43.263121Z","end":"2026-04-20T04:55:43.370682Z","steps":["trace[1366299553] 'read index received' (duration: 17.692416ms)","trace[1366299553] 'applied index is now lower than readState.Index' (duration: 89.866903ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:55:43.370736Z","caller":"traceutil/trace.go:171","msg":"trace[787463985] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"115.022648ms","start":"2026-04-20T04:55:43.255672Z","end":"2026-04-20T04:55:43.370695Z","steps":["trace[787463985] 'process raft request' (duration: 25.132785ms)","trace[787463985] 'compare' (duration: 89.643239ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:55:43.370816Z","caller":"traceutil/trace.go:171","msg":"trace[82713680] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"111.627965ms","start":"2026-04-20T04:55:43.259177Z","end":"2026-04-20T04:55:43.370805Z","steps":["trace[82713680] 'process raft request' (duration: 111.441311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:55:43.37104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.904126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-20T04:55:43.371163Z","caller":"traceutil/trace.go:171","msg":"trace[318947411] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2198; }","duration":"108.055189ms","start":"2026-04-20T04:55:43.263089Z","end":"2026-04-20T04:55:43.371144Z","steps":["trace[318947411] 'agreement among raft nodes before linearized reading' (duration: 107.814365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:57:02.559545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.399183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:57:02.559689Z","caller":"traceutil/trace.go:171","msg":"trace[2113277361] transaction","detail":"{read_only:false; response_revision:2821; number_of_response:1; }","duration":"192.811245ms","start":"2026-04-20T04:57:02.366862Z","end":"2026-04-20T04:57:02.559673Z","steps":["trace[2113277361] 'process raft request' (duration: 54.203428ms)","trace[2113277361] 'compare' (duration: 138.304971ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:57:08.70478Z","caller":"traceutil/trace.go:171","msg":"trace[1515169170] transaction","detail":"{read_only:false; response_revision:2856; number_of_response:1; }","duration":"118.821249ms","start":"2026-04-20T04:57:08.585924Z","end":"2026-04-20T04:57:08.704745Z","steps":["trace[1515169170] 'process raft request' (duration: 65.753922ms)","trace[1515169170] 'compare' (duration: 52.904703ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:59:28.529259Z","caller":"traceutil/trace.go:171","msg":"trace[594643489] transaction","detail":"{read_only:false; response_revision:3790; number_of_response:1; }","duration":"129.345853ms","start":"2026-04-20T04:59:28.399884Z","end":"2026-04-20T04:59:28.529229Z","steps":["trace[594643489] 'process raft request' (duration: 128.65973ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:28.78996Z","caller":"traceutil/trace.go:171","msg":"trace[1480537547] transaction","detail":"{read_only:false; response_revision:3791; number_of_response:1; }","duration":"257.453631ms","start":"2026-04-20T04:59:28.532467Z","end":"2026-04-20T04:59:28.78992Z","steps":["trace[1480537547] 'process raft request' (duration: 213.169602ms)","trace[1480537547] 'compare' (duration: 43.857892ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:00:52.151147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.011547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T05:00:52.151249Z","caller":"traceutil/trace.go:171","msg":"trace[204962995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4943; }","duration":"226.15159ms","start":"2026-04-20T05:00:51.925078Z","end":"2026-04-20T05:00:52.15123Z","steps":["trace[204962995] 'range keys from in-memory index tree' (duration: 225.852014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:55.798384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.874743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:00:55.798562Z","caller":"traceutil/trace.go:171","msg":"trace[1341467319] transaction","detail":"{read_only:false; response_revision:5034; number_of_response:1; }","duration":"169.447632ms","start":"2026-04-20T05:00:55.629092Z","end":"2026-04-20T05:00:55.79854Z","steps":["trace[1341467319] 'process raft request' (duration: 50.310215ms)","trace[1341467319] 'compare' (duration: 118.71606ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:01:11.039103Z","caller":"traceutil/trace.go:171","msg":"trace[86368279] transaction","detail":"{read_only:false; response_revision:5314; number_of_response:1; }","duration":"106.865117ms","start":"2026-04-20T05:01:10.932213Z","end":"2026-04-20T05:01:11.039078Z","steps":["trace[86368279] 'process raft request' (duration: 58.5306ms)","trace[86368279] 'compare' (duration: 48.218415ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:01:12.974627Z","caller":"traceutil/trace.go:171","msg":"trace[460361475] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"100.464477ms","start":"2026-04-20T05:01:12.874135Z","end":"2026-04-20T05:01:12.9746Z","steps":["trace[460361475] 'process raft request' (duration: 100.281432ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:26.444206Z","caller":"traceutil/trace.go:171","msg":"trace[922028756] linearizableReadLoop","detail":"{readStateIndex:5727; appliedIndex:5726; }","duration":"121.091615ms","start":"2026-04-20T05:01:26.32308Z","end":"2026-04-20T05:01:26.444172Z","steps":["trace[922028756] 'read index received' (duration: 120.784719ms)","trace[922028756] 'applied index is now lower than readState.Index' (duration: 305.276µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:01:26.444301Z","caller":"traceutil/trace.go:171","msg":"trace[1667853811] transaction","detail":"{read_only:false; response_revision:5526; number_of_response:1; }","duration":"122.663175ms","start":"2026-04-20T05:01:26.321602Z","end":"2026-04-20T05:01:26.444265Z","steps":["trace[1667853811] 'process raft request' (duration: 122.368309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:26.444761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.585094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:81542"} {"level":"info","ts":"2026-04-20T05:01:26.444833Z","caller":"traceutil/trace.go:171","msg":"trace[543331046] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5526; }","duration":"121.750087ms","start":"2026-04-20T05:01:26.323062Z","end":"2026-04-20T05:01:26.444812Z","steps":["trace[543331046] 'agreement among raft nodes before linearized reading' (duration: 121.259978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:01:27.06619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.164435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:01:27.066295Z","caller":"traceutil/trace.go:171","msg":"trace[1801281148] linearizableReadLoop","detail":"{readStateIndex:5736; appliedIndex:5732; }","duration":"177.581895ms","start":"2026-04-20T05:01:26.888695Z","end":"2026-04-20T05:01:27.066277Z","steps":["trace[1801281148] 'read index received' (duration: 1.312034ms)","trace[1801281148] 'applied index is now lower than readState.Index' (duration: 176.268871ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:01:27.066476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.784299ms","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-04-20T05:01:27.066516Z","caller":"traceutil/trace.go:171","msg":"trace[1255846633] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5535; }","duration":"177.867601ms","start":"2026-04-20T05:01:26.888634Z","end":"2026-04-20T05:01:27.066502Z","steps":["trace[1255846633] 'agreement among raft nodes before linearized reading' (duration: 177.722658ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:01:27.066494Z","caller":"traceutil/trace.go:171","msg":"trace[1030685591] transaction","detail":"{read_only:false; response_revision:5535; number_of_response:1; }","duration":"190.814614ms","start":"2026-04-20T05:01:26.875624Z","end":"2026-04-20T05:01:27.066438Z","steps":["trace[1030685591] 'process raft request' (duration: 27.306123ms)","trace[1030685591] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/services/endpoints/openstack/rabbitmq-keystone-nodes; req_size:948; } (duration: 162.87555ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:02:40.133677Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3025} {"level":"info","ts":"2026-04-20T05:02:40.235406Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3025,"took":"100.02744ms","hash":2767932869,"current-db-size-bytes":57245696,"current-db-size":"57 MB","current-db-size-in-use-bytes":31465472,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-20T05:02:40.235674Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2767932869,"revision":3025,"compact-revision":-1} {"level":"info","ts":"2026-04-20T05:07:19.752785Z","caller":"traceutil/trace.go:171","msg":"trace[1021761936] linearizableReadLoop","detail":"{readStateIndex:8484; appliedIndex:8483; }","duration":"286.529605ms","start":"2026-04-20T05:07:19.466232Z","end":"2026-04-20T05:07:19.752762Z","steps":["trace[1021761936] 'read index received' (duration: 286.328891ms)","trace[1021761936] 'applied index is now lower than readState.Index' (duration: 199.244µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:07:19.752919Z","caller":"traceutil/trace.go:171","msg":"trace[251574699] transaction","detail":"{read_only:false; response_revision:8198; number_of_response:1; }","duration":"303.426512ms","start":"2026-04-20T05:07:19.449435Z","end":"2026-04-20T05:07:19.752862Z","steps":["trace[251574699] 'process raft request' (duration: 303.148157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:19.75299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.721828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:07:19.753098Z","caller":"traceutil/trace.go:171","msg":"trace[444310682] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:8198; }","duration":"286.891481ms","start":"2026-04-20T05:07:19.466175Z","end":"2026-04-20T05:07:19.753066Z","steps":["trace[444310682] 'agreement among raft nodes before linearized reading' (duration: 286.709868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:19.753119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:19.449416Z","time spent":"303.627196ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:07:19.93908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.713832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:07:19.939185Z","caller":"traceutil/trace.go:171","msg":"trace[1482968114] linearizableReadLoop","detail":"{readStateIndex:8485; appliedIndex:8484; }","duration":"186.309332ms","start":"2026-04-20T05:07:19.752859Z","end":"2026-04-20T05:07:19.939168Z","steps":["trace[1482968114] 'read index received' (duration: 18.394536ms)","trace[1482968114] 'applied index is now lower than readState.Index' (duration: 167.913446ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:07:19.939356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.984163ms","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-04-20T05:07:19.939386Z","caller":"traceutil/trace.go:171","msg":"trace[1299557335] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8199; }","duration":"420.054384ms","start":"2026-04-20T05:07:19.51932Z","end":"2026-04-20T05:07:19.939374Z","steps":["trace[1299557335] 'agreement among raft nodes before linearized reading' (duration: 419.893931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:19.93942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:19.519301Z","time spent":"420.111425ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-20T05:07:19.939689Z","caller":"traceutil/trace.go:171","msg":"trace[438349036] transaction","detail":"{read_only:false; response_revision:8199; number_of_response:1; }","duration":"415.719183ms","start":"2026-04-20T05:07:19.523945Z","end":"2026-04-20T05:07:19.939665Z","steps":["trace[438349036] 'process raft request' (duration: 247.325668ms)","trace[438349036] 'compare' (duration: 167.468007ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:07:19.939803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:19.523927Z","time spent":"415.818924ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:07:31.417085Z","caller":"traceutil/trace.go:171","msg":"trace[799256320] linearizableReadLoop","detail":"{readStateIndex:8549; appliedIndex:8548; }","duration":"157.662283ms","start":"2026-04-20T05:07:31.259394Z","end":"2026-04-20T05:07:31.417057Z","steps":["trace[799256320] 'read index received' (duration: 157.407629ms)","trace[799256320] 'applied index is now lower than readState.Index' (duration: 253.284µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:07:31.417242Z","caller":"traceutil/trace.go:171","msg":"trace[225737120] transaction","detail":"{read_only:false; response_revision:8260; number_of_response:1; }","duration":"161.595967ms","start":"2026-04-20T05:07:31.255594Z","end":"2026-04-20T05:07:31.41719Z","steps":["trace[225737120] 'process raft request' (duration: 161.272511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:31.417343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.916378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T05:07:31.417389Z","caller":"traceutil/trace.go:171","msg":"trace[833838413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8260; }","duration":"157.999399ms","start":"2026-04-20T05:07:31.259378Z","end":"2026-04-20T05:07:31.417377Z","steps":["trace[833838413] 'agreement among raft nodes before linearized reading' (duration: 157.825806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:31.417408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.846273ms","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-04-20T05:07:31.417464Z","caller":"traceutil/trace.go:171","msg":"trace[1238462761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8260; }","duration":"135.957464ms","start":"2026-04-20T05:07:31.281494Z","end":"2026-04-20T05:07:31.417451Z","steps":["trace[1238462761] 'agreement among raft nodes before linearized reading' (duration: 135.756101ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:31.593362Z","caller":"traceutil/trace.go:171","msg":"trace[1645014548] transaction","detail":"{read_only:false; response_revision:8261; number_of_response:1; }","duration":"171.844ms","start":"2026-04-20T05:07:31.421476Z","end":"2026-04-20T05:07:31.59332Z","steps":["trace[1645014548] 'process raft request' (duration: 145.854311ms)","trace[1645014548] 'compare' (duration: 25.820566ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:07:31.613397Z","caller":"traceutil/trace.go:171","msg":"trace[748128020] transaction","detail":"{read_only:false; response_revision:8262; number_of_response:1; }","duration":"191.283395ms","start":"2026-04-20T05:07:31.422091Z","end":"2026-04-20T05:07:31.613374Z","steps":["trace[748128020] 'process raft request' (duration: 191.167663ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:32.510054Z","caller":"traceutil/trace.go:171","msg":"trace[636820142] transaction","detail":"{read_only:false; response_revision:8268; number_of_response:1; }","duration":"310.438994ms","start":"2026-04-20T05:07:32.199036Z","end":"2026-04-20T05:07:32.509475Z","steps":["trace[636820142] 'process raft request' (duration: 310.19057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:32.510421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:07:32.199019Z","time spent":"311.25954ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:07:32.654194Z","caller":"traceutil/trace.go:171","msg":"trace[1709621712] transaction","detail":"{read_only:false; response_revision:8269; number_of_response:1; }","duration":"179.258129ms","start":"2026-04-20T05:07:32.474891Z","end":"2026-04-20T05:07:32.654149Z","steps":["trace[1709621712] 'process raft request' (duration: 94.279002ms)","trace[1709621712] 'compare' (duration: 84.479918ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:07:33.594551Z","caller":"traceutil/trace.go:171","msg":"trace[1004248484] transaction","detail":"{read_only:false; response_revision:8272; number_of_response:1; }","duration":"144.385403ms","start":"2026-04-20T05:07:33.450123Z","end":"2026-04-20T05:07:33.594508Z","steps":["trace[1004248484] 'process raft request' (duration: 113.666515ms)","trace[1004248484] 'compare' (duration: 30.577775ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:07:33.605814Z","caller":"traceutil/trace.go:171","msg":"trace[2008913594] transaction","detail":"{read_only:false; response_revision:8273; number_of_response:1; }","duration":"153.172498ms","start":"2026-04-20T05:07:33.452628Z","end":"2026-04-20T05:07:33.6058Z","steps":["trace[2008913594] 'process raft request' (duration: 152.941094ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:33.774388Z","caller":"traceutil/trace.go:171","msg":"trace[1945386333] linearizableReadLoop","detail":"{readStateIndex:8565; appliedIndex:8564; }","duration":"102.828972ms","start":"2026-04-20T05:07:33.671522Z","end":"2026-04-20T05:07:33.774351Z","steps":["trace[1945386333] 'read index received' (duration: 41.804496ms)","trace[1945386333] 'applied index is now lower than readState.Index' (duration: 61.023366ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:07:33.774519Z","caller":"traceutil/trace.go:171","msg":"trace[1753679674] transaction","detail":"{read_only:false; response_revision:8275; number_of_response:1; }","duration":"152.20113ms","start":"2026-04-20T05:07:33.622274Z","end":"2026-04-20T05:07:33.774475Z","steps":["trace[1753679674] 'process raft request' (duration: 91.109972ms)","trace[1753679674] 'compare' (duration: 60.804022ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:07:33.774566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.026516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:07:33.774678Z","caller":"traceutil/trace.go:171","msg":"trace[987871718] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8275; }","duration":"103.175429ms","start":"2026-04-20T05:07:33.671472Z","end":"2026-04-20T05:07:33.774647Z","steps":["trace[987871718] 'agreement among raft nodes before linearized reading' (duration: 103.005956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:34.272149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.145329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:07:34.272259Z","caller":"traceutil/trace.go:171","msg":"trace[1492196434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8276; }","duration":"119.316432ms","start":"2026-04-20T05:07:34.152923Z","end":"2026-04-20T05:07:34.27224Z","steps":["trace[1492196434] 'range keys from in-memory index tree' (duration: 118.995776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:07:34.272286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.63541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-20T05:07:34.272346Z","caller":"traceutil/trace.go:171","msg":"trace[415198521] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8276; }","duration":"200.770093ms","start":"2026-04-20T05:07:34.07156Z","end":"2026-04-20T05:07:34.27233Z","steps":["trace[415198521] 'range keys from in-memory index tree' (duration: 200.338354ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:40.142736Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6119} {"level":"info","ts":"2026-04-20T05:07:40.245395Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6119,"took":"100.038079ms","hash":2088335228,"current-db-size-bytes":57245696,"current-db-size":"57 MB","current-db-size-in-use-bytes":23658496,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-20T05:07:40.245476Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2088335228,"revision":6119,"compact-revision":3025} {"level":"info","ts":"2026-04-20T05:08:01.894844Z","caller":"traceutil/trace.go:171","msg":"trace[2061743559] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"128.26121ms","start":"2026-04-20T05:08:01.766555Z","end":"2026-04-20T05:08:01.894817Z","steps":["trace[2061743559] 'process raft request' (duration: 127.810181ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:01.896453Z","caller":"traceutil/trace.go:171","msg":"trace[1000586874] transaction","detail":"{read_only:false; response_revision:8478; number_of_response:1; }","duration":"127.291463ms","start":"2026-04-20T05:08:01.769144Z","end":"2026-04-20T05:08:01.896436Z","steps":["trace[1000586874] 'process raft request' (duration: 125.391116ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:04.63016Z","caller":"traceutil/trace.go:171","msg":"trace[1383665049] transaction","detail":"{read_only:false; response_revision:8494; number_of_response:1; }","duration":"162.584715ms","start":"2026-04-20T05:08:04.467556Z","end":"2026-04-20T05:08:04.630141Z","steps":["trace[1383665049] 'process raft request' (duration: 162.28984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:04.63038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.417973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-20T05:08:04.630449Z","caller":"traceutil/trace.go:171","msg":"trace[1530404748] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8494; }","duration":"161.546356ms","start":"2026-04-20T05:08:04.468888Z","end":"2026-04-20T05:08:04.630435Z","steps":["trace[1530404748] 'agreement among raft nodes before linearized reading' (duration: 161.330892ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:04.630086Z","caller":"traceutil/trace.go:171","msg":"trace[2002726683] linearizableReadLoop","detail":"{readStateIndex:8793; appliedIndex:8792; }","duration":"161.109768ms","start":"2026-04-20T05:08:04.468943Z","end":"2026-04-20T05:08:04.630053Z","steps":["trace[2002726683] 'read index received' (duration: 160.800212ms)","trace[2002726683] 'applied index is now lower than readState.Index' (duration: 307.936µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:08:05.092495Z","caller":"traceutil/trace.go:171","msg":"trace[1007605171] linearizableReadLoop","detail":"{readStateIndex:8795; appliedIndex:8794; }","duration":"221.880289ms","start":"2026-04-20T05:08:04.870584Z","end":"2026-04-20T05:08:05.092464Z","steps":["trace[1007605171] 'read index received' (duration: 221.599244ms)","trace[1007605171] 'applied index is now lower than readState.Index' (duration: 279.375µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:08:05.092633Z","caller":"traceutil/trace.go:171","msg":"trace[1693934969] transaction","detail":"{read_only:false; response_revision:8496; number_of_response:1; }","duration":"307.526299ms","start":"2026-04-20T05:08:04.78505Z","end":"2026-04-20T05:08:05.092577Z","steps":["trace[1693934969] 'process raft request' (duration: 307.181172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:05.092801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.192016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qqxxw\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T05:08:05.092863Z","caller":"traceutil/trace.go:171","msg":"trace[378504879] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qqxxw; range_end:; response_count:1; response_revision:8496; }","duration":"222.278407ms","start":"2026-04-20T05:08:04.870567Z","end":"2026-04-20T05:08:05.092845Z","steps":["trace[378504879] 'agreement among raft nodes before linearized reading' (duration: 222.022833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:05.092934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:08:04.785026Z","time spent":"307.727263ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:08:05.20911Z","caller":"traceutil/trace.go:171","msg":"trace[14108793] linearizableReadLoop","detail":"{readStateIndex:8797; appliedIndex:8795; }","duration":"111.339222ms","start":"2026-04-20T05:08:05.097738Z","end":"2026-04-20T05:08:05.209077Z","steps":["trace[14108793] 'read index received' (duration: 37.433214ms)","trace[14108793] 'applied index is now lower than readState.Index' (duration: 73.904648ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:08:05.209199Z","caller":"traceutil/trace.go:171","msg":"trace[1853741968] transaction","detail":"{read_only:false; response_revision:8497; number_of_response:1; }","duration":"158.173633ms","start":"2026-04-20T05:08:05.050977Z","end":"2026-04-20T05:08:05.209151Z","steps":["trace[1853741968] 'process raft request' (duration: 84.218583ms)","trace[1853741968] 'compare' (duration: 73.620634ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:08:05.209285Z","caller":"traceutil/trace.go:171","msg":"trace[844010973] transaction","detail":"{read_only:false; response_revision:8498; number_of_response:1; }","duration":"136.108418ms","start":"2026-04-20T05:08:05.073144Z","end":"2026-04-20T05:08:05.209252Z","steps":["trace[844010973] 'process raft request' (duration: 135.860053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:05.209657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.892682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228591"} {"level":"info","ts":"2026-04-20T05:08:05.209711Z","caller":"traceutil/trace.go:171","msg":"trace[213371161] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8498; }","duration":"112.004245ms","start":"2026-04-20T05:08:05.097695Z","end":"2026-04-20T05:08:05.2097Z","steps":["trace[213371161] 'agreement among raft nodes before linearized reading' (duration: 111.544496ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:06.275969Z","caller":"traceutil/trace.go:171","msg":"trace[1864633890] transaction","detail":"{read_only:false; response_revision:8499; number_of_response:1; }","duration":"353.399732ms","start":"2026-04-20T05:08:05.922538Z","end":"2026-04-20T05:08:06.275938Z","steps":["trace[1864633890] 'process raft request' (duration: 353.176678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:06.276162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:08:05.922514Z","time spent":"353.550505ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:08:06.33713Z","caller":"traceutil/trace.go:171","msg":"trace[540087202] transaction","detail":"{read_only:false; response_revision:8500; number_of_response:1; }","duration":"414.216774ms","start":"2026-04-20T05:08:05.922896Z","end":"2026-04-20T05:08:06.337113Z","steps":["trace[540087202] 'process raft request' (duration: 414.01557ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:06.337112Z","caller":"traceutil/trace.go:171","msg":"trace[790965439] linearizableReadLoop","detail":"{readStateIndex:8799; appliedIndex:8797; }","duration":"393.745409ms","start":"2026-04-20T05:08:05.943335Z","end":"2026-04-20T05:08:06.33708Z","steps":["trace[790965439] 'read index received' (duration: 332.487558ms)","trace[790965439] 'applied index is now lower than readState.Index' (duration: 61.256301ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:08:06.337196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:08:05.922871Z","time spent":"414.294195ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:08:06.33738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.433121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-20T05:08:06.33739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.954039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:08:06.337419Z","caller":"traceutil/trace.go:171","msg":"trace[1237254901] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8500; }","duration":"246.548403ms","start":"2026-04-20T05:08:06.090862Z","end":"2026-04-20T05:08:06.33741Z","steps":["trace[1237254901] 'agreement among raft nodes before linearized reading' (duration: 246.370099ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:06.337426Z","caller":"traceutil/trace.go:171","msg":"trace[249238641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8500; }","duration":"183.02505ms","start":"2026-04-20T05:08:06.154389Z","end":"2026-04-20T05:08:06.337414Z","steps":["trace[249238641] 'agreement among raft nodes before linearized reading' (duration: 182.967269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:06.33741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.041845ms","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-04-20T05:08:06.337493Z","caller":"traceutil/trace.go:171","msg":"trace[1810188091] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8500; }","duration":"394.180688ms","start":"2026-04-20T05:08:05.943294Z","end":"2026-04-20T05:08:06.337474Z","steps":["trace[1810188091] 'agreement among raft nodes before linearized reading' (duration: 393.886522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:06.33754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:08:05.943273Z","time spent":"394.259129ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T05:08:06.565279Z","caller":"traceutil/trace.go:171","msg":"trace[2111523216] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"222.543162ms","start":"2026-04-20T05:08:06.342698Z","end":"2026-04-20T05:08:06.565241Z","steps":["trace[2111523216] 'process raft request' (duration: 174.685582ms)","trace[2111523216] 'compare' (duration: 47.691297ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:08:06.838308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.416314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:08:06.838439Z","caller":"traceutil/trace.go:171","msg":"trace[1939968943] linearizableReadLoop","detail":"{readStateIndex:8802; appliedIndex:8801; }","duration":"130.903209ms","start":"2026-04-20T05:08:06.707515Z","end":"2026-04-20T05:08:06.838418Z","steps":["trace[1939968943] 'read index received' (duration: 29.54µs)","trace[1939968943] 'applied index is now lower than readState.Index' (duration: 130.871879ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:08:06.838503Z","caller":"traceutil/trace.go:171","msg":"trace[678840384] transaction","detail":"{read_only:false; response_revision:8503; number_of_response:1; }","duration":"197.813847ms","start":"2026-04-20T05:08:06.640654Z","end":"2026-04-20T05:08:06.838468Z","steps":["trace[678840384] 'process raft request' (duration: 45.126458ms)","trace[678840384] 'compare' (duration: 152.16786ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:08:06.838704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.184775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T05:08:06.838756Z","caller":"traceutil/trace.go:171","msg":"trace[1284571337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8503; }","duration":"131.265917ms","start":"2026-04-20T05:08:06.707478Z","end":"2026-04-20T05:08:06.838743Z","steps":["trace[1284571337] 'agreement among raft nodes before linearized reading' (duration: 131.047833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:06.838743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.423933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:08:06.838801Z","caller":"traceutil/trace.go:171","msg":"trace[863417901] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8503; }","duration":"129.531345ms","start":"2026-04-20T05:08:06.709255Z","end":"2026-04-20T05:08:06.838787Z","steps":["trace[863417901] 'agreement among raft nodes before linearized reading' (duration: 129.301331ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:14.564762Z","caller":"traceutil/trace.go:171","msg":"trace[1152943290] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"299.218563ms","start":"2026-04-20T05:08:14.26542Z","end":"2026-04-20T05:08:14.564638Z","steps":["trace[1152943290] 'process raft request' (duration: 298.976978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:14.564917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.584148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T05:08:14.564951Z","caller":"traceutil/trace.go:171","msg":"trace[1542009173] linearizableReadLoop","detail":"{readStateIndex:8889; appliedIndex:8888; }","duration":"251.227011ms","start":"2026-04-20T05:08:14.313318Z","end":"2026-04-20T05:08:14.564545Z","steps":["trace[1542009173] 'read index received' (duration: 250.986996ms)","trace[1542009173] 'applied index is now lower than readState.Index' (duration: 239.065µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:08:14.565001Z","caller":"traceutil/trace.go:171","msg":"trace[451173800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8585; }","duration":"251.72165ms","start":"2026-04-20T05:08:14.31326Z","end":"2026-04-20T05:08:14.564981Z","steps":["trace[451173800] 'agreement among raft nodes before linearized reading' (duration: 251.464536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:14.565335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.509322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T05:08:14.565414Z","caller":"traceutil/trace.go:171","msg":"trace[349097470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8585; }","duration":"180.638174ms","start":"2026-04-20T05:08:14.384733Z","end":"2026-04-20T05:08:14.565371Z","steps":["trace[349097470] 'agreement among raft nodes before linearized reading' (duration: 180.431421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:15.420623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.983666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:08:15.4212Z","caller":"traceutil/trace.go:171","msg":"trace[173007041] transaction","detail":"{read_only:false; response_revision:8593; number_of_response:1; }","duration":"286.719808ms","start":"2026-04-20T05:08:15.134387Z","end":"2026-04-20T05:08:15.421106Z","steps":["trace[173007041] 'process raft request' (duration: 60.152521ms)","trace[173007041] 'compare' (duration: 225.845014ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:08:15.421314Z","caller":"traceutil/trace.go:171","msg":"trace[1020340595] linearizableReadLoop","detail":"{readStateIndex:8898; appliedIndex:8896; }","duration":"268.559206ms","start":"2026-04-20T05:08:15.152724Z","end":"2026-04-20T05:08:15.421283Z","steps":["trace[1020340595] 'read index received' (duration: 42.008069ms)","trace[1020340595] 'applied index is now lower than readState.Index' (duration: 226.545257ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:08:15.421314Z","caller":"traceutil/trace.go:171","msg":"trace[1771911621] transaction","detail":"{read_only:false; response_revision:8596; number_of_response:1; }","duration":"165.227035ms","start":"2026-04-20T05:08:15.256038Z","end":"2026-04-20T05:08:15.421265Z","steps":["trace[1771911621] 'process raft request' (duration: 165.092893ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:15.421474Z","caller":"traceutil/trace.go:171","msg":"trace[2048585472] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"286.543195ms","start":"2026-04-20T05:08:15.134915Z","end":"2026-04-20T05:08:15.421458Z","steps":["trace[2048585472] 'process raft request' (duration: 285.855282ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:15.421628Z","caller":"traceutil/trace.go:171","msg":"trace[611370972] transaction","detail":"{read_only:false; response_revision:8595; number_of_response:1; }","duration":"168.353095ms","start":"2026-04-20T05:08:15.253206Z","end":"2026-04-20T05:08:15.421559Z","steps":["trace[611370972] 'process raft request' (duration: 167.828514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:08:15.421731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.030056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T05:08:15.421712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.535608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qqxxw\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T05:08:15.421811Z","caller":"traceutil/trace.go:171","msg":"trace[1893267125] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qqxxw; range_end:; response_count:1; response_revision:8596; }","duration":"151.67033ms","start":"2026-04-20T05:08:15.270119Z","end":"2026-04-20T05:08:15.42179Z","steps":["trace[1893267125] 'agreement among raft nodes before linearized reading' (duration: 151.446326ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:15.421819Z","caller":"traceutil/trace.go:171","msg":"trace[813078135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8596; }","duration":"269.134988ms","start":"2026-04-20T05:08:15.152638Z","end":"2026-04-20T05:08:15.421773Z","steps":["trace[813078135] 'agreement among raft nodes before linearized reading' (duration: 268.968725ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:08:15.576117Z","caller":"traceutil/trace.go:171","msg":"trace[1072474742] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"141.55283ms","start":"2026-04-20T05:08:15.434516Z","end":"2026-04-20T05:08:15.576069Z","steps":["trace[1072474742] 'process raft request' (duration: 107.834817ms)","trace[1072474742] 'compare' (duration: 33.498029ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:09:07.320366Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000248a.wal"} {"level":"info","ts":"2026-04-20T05:10:17.465841Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T05:10:17.472536Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T05:10:17.472623Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T05:12:40.149724Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8315} {"level":"info","ts":"2026-04-20T05:12:40.242632Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8315,"took":"90.63295ms","hash":3774031713,"current-db-size-bytes":57245696,"current-db-size":"57 MB","current-db-size-in-use-bytes":24813568,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-20T05:12:40.242708Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3774031713,"revision":8315,"compact-revision":6119} {"level":"info","ts":"2026-04-20T05:17:40.157097Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10699} {"level":"info","ts":"2026-04-20T05:17:40.238349Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10699,"took":"78.910454ms","hash":3381916224,"current-db-size-bytes":57245696,"current-db-size":"57 MB","current-db-size-in-use-bytes":20299776,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-20T05:17:40.238413Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3381916224,"revision":10699,"compact-revision":8315}