{"level":"warn","ts":"2026-04-16T07:01:09.862286Z","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-16T07:01:09.862382Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-16T07:01:09.862447Z","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-16T07:01:09.862457Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-16T07:01:09.862477Z","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-16T07:01:09.86311Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-04-16T07:01:09.863184Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-16T07:01:09.868514Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.134179ms"} {"level":"info","ts":"2026-04-16T07:01:09.877135Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-04-16T07:01:09.87727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T07:01:09.877338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-04-16T07:01:09.877361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T07:01:09.877373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-04-16T07:01:09.87752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-04-16T07:01:09.88756Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T07:01:09.888885Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T07:01:09.889945Z","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-16T07:01:09.89182Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T07:01:09.892021Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T07:01:09.892149Z","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-16T07:01:09.892438Z","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-16T07:01:09.892464Z","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-16T07:01:09.89404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-04-16T07:01:09.894652Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-16T07:01:09.896688Z","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-16T07:01:09.896843Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-16T07:01:09.896899Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-16T07:01:09.897118Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T07:01:09.897157Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T07:01:10.878037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T07:01:10.87813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T07:01:10.878195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-04-16T07:01:10.878219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-04-16T07:01:10.878237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-16T07:01:10.878254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-04-16T07:01:10.878275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-16T07:01:10.879653Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T07:01:10.879735Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T07:01:10.879788Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T07:01:10.879689Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T07:01:10.880184Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T07:01:10.880309Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T07:01:10.881064Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T07:01:10.881293Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T07:01:10.881391Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T07:01:10.883684Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-04-16T07:01:10.884269Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-16T07:01:26.629667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.966788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T07:01:26.629778Z","caller":"traceutil/trace.go:171","msg":"trace[574944296] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"132.2094ms","start":"2026-04-16T07:01:26.497548Z","end":"2026-04-16T07:01:26.629758Z","steps":["trace[574944296] 'read index received' (duration: 33.72µs)","trace[574944296] 'applied index is now lower than readState.Index' (duration: 132.17446ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:01:26.629845Z","caller":"traceutil/trace.go:171","msg":"trace[1508444530] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"316.376135ms","start":"2026-04-16T07:01:26.313434Z","end":"2026-04-16T07:01:26.62981Z","steps":["trace[1508444530] 'process raft request' (duration: 120.581944ms)","trace[1508444530] 'compare' (duration: 194.855936ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T07:01:26.629956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T07:01:26.31341Z","time spent":"316.495416ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T07:01:26.630096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.431514ms","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-16T07:01:26.630263Z","caller":"traceutil/trace.go:171","msg":"trace[345108602] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:400; }","duration":"132.689548ms","start":"2026-04-16T07:01:26.497535Z","end":"2026-04-16T07:01:26.630224Z","steps":["trace[345108602] 'agreement among raft nodes before linearized reading' (duration: 132.308402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:01:36.779556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.047903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-16T07:01:36.779631Z","caller":"traceutil/trace.go:171","msg":"trace[597225009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"102.174126ms","start":"2026-04-16T07:01:36.677441Z","end":"2026-04-16T07:01:36.779615Z","steps":["trace[597225009] 'range keys from in-memory index tree' (duration: 101.908831ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:01:42.690678Z","caller":"traceutil/trace.go:171","msg":"trace[1608197753] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"223.558365ms","start":"2026-04-16T07:01:42.467091Z","end":"2026-04-16T07:01:42.69065Z","steps":["trace[1608197753] 'read index received' (duration: 222.872063ms)","trace[1608197753] 'applied index is now lower than readState.Index' (duration: 685.322µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:01:42.690759Z","caller":"traceutil/trace.go:171","msg":"trace[1661616145] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"313.802576ms","start":"2026-04-16T07:01:42.376897Z","end":"2026-04-16T07:01:42.690699Z","steps":["trace[1661616145] 'process raft request' (duration: 313.317278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:01:42.690932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.832478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T07:01:42.69099Z","caller":"traceutil/trace.go:171","msg":"trace[345629588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"223.94577ms","start":"2026-04-16T07:01:42.467028Z","end":"2026-04-16T07:01:42.690974Z","steps":["trace[345629588] 'agreement among raft nodes before linearized reading' (duration: 223.756738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:01:42.690994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T07:01:42.376871Z","time spent":"313.98374ms","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T07:06:34.3047Z","caller":"traceutil/trace.go:171","msg":"trace[2009516424] transaction","detail":"{read_only:false; response_revision:1741; number_of_response:1; }","duration":"138.57249ms","start":"2026-04-16T07:06:34.166107Z","end":"2026-04-16T07:06:34.304679Z","steps":["trace[2009516424] 'process raft request' (duration: 138.315414ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:07:01.75128Z","caller":"traceutil/trace.go:171","msg":"trace[1441880936] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"109.095068ms","start":"2026-04-16T07:07:01.642135Z","end":"2026-04-16T07:07:01.75123Z","steps":["trace[1441880936] 'process raft request' (duration: 108.904786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:07:01.98834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.309417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T07:07:01.988444Z","caller":"traceutil/trace.go:171","msg":"trace[95204072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1830; }","duration":"184.434759ms","start":"2026-04-16T07:07:01.80399Z","end":"2026-04-16T07:07:01.988425Z","steps":["trace[95204072] 'range keys from in-memory index tree' (duration: 184.159304ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:07:26.74722Z","caller":"traceutil/trace.go:171","msg":"trace[1270576618] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"167.519498ms","start":"2026-04-16T07:07:26.579673Z","end":"2026-04-16T07:07:26.747192Z","steps":["trace[1270576618] 'process raft request' (duration: 167.354096ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:07:26.757168Z","caller":"traceutil/trace.go:171","msg":"trace[1193492095] linearizableReadLoop","detail":"{readStateIndex:2005; appliedIndex:2003; }","duration":"173.574489ms","start":"2026-04-16T07:07:26.583567Z","end":"2026-04-16T07:07:26.757142Z","steps":["trace[1193492095] 'read index received' (duration: 163.442489ms)","trace[1193492095] 'applied index is now lower than readState.Index' (duration: 10.1309ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:07:26.757265Z","caller":"traceutil/trace.go:171","msg":"trace[474364661] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"174.38225ms","start":"2026-04-16T07:07:26.582836Z","end":"2026-04-16T07:07:26.757219Z","steps":["trace[474364661] 'process raft request' (duration: 174.190797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:07:26.757452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.877443ms","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-16T07:07:26.757548Z","caller":"traceutil/trace.go:171","msg":"trace[2016292965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1914; }","duration":"174.046475ms","start":"2026-04-16T07:07:26.583484Z","end":"2026-04-16T07:07:26.75753Z","steps":["trace[2016292965] 'agreement among raft nodes before linearized reading' (duration: 173.767441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:07:26.757484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.183502ms","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-16T07:07:26.757727Z","caller":"traceutil/trace.go:171","msg":"trace[537216745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1914; }","duration":"173.452317ms","start":"2026-04-16T07:07:26.584257Z","end":"2026-04-16T07:07:26.757709Z","steps":["trace[537216745] 'agreement among raft nodes before linearized reading' (duration: 173.021991ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:11:10.915197Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1664} {"level":"info","ts":"2026-04-16T07:11:10.976666Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1664,"took":"60.754626ms","hash":3864965969,"current-db-size-bytes":22536192,"current-db-size":"22 MB","current-db-size-in-use-bytes":5292032,"current-db-size-in-use":"5.3 MB"} {"level":"info","ts":"2026-04-16T07:11:10.97675Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3864965969,"revision":1664,"compact-revision":-1} {"level":"info","ts":"2026-04-16T07:12:29.776572Z","caller":"traceutil/trace.go:171","msg":"trace[78929255] transaction","detail":"{read_only:false; response_revision:2943; number_of_response:1; }","duration":"102.887065ms","start":"2026-04-16T07:12:29.673668Z","end":"2026-04-16T07:12:29.776555Z","steps":["trace[78929255] 'process raft request' (duration: 102.798613ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:12:36.948596Z","caller":"traceutil/trace.go:171","msg":"trace[515849459] linearizableReadLoop","detail":"{readStateIndex:3680; appliedIndex:3679; }","duration":"233.443361ms","start":"2026-04-16T07:12:36.715121Z","end":"2026-04-16T07:12:36.948565Z","steps":["trace[515849459] 'read index received' (duration: 215.256764ms)","trace[515849459] 'applied index is now lower than readState.Index' (duration: 18.185067ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:12:36.94864Z","caller":"traceutil/trace.go:171","msg":"trace[1055291872] transaction","detail":"{read_only:false; response_revision:3519; number_of_response:1; }","duration":"245.285573ms","start":"2026-04-16T07:12:36.703319Z","end":"2026-04-16T07:12:36.948604Z","steps":["trace[1055291872] 'process raft request' (duration: 227.130776ms)","trace[1055291872] 'compare' (duration: 17.749877ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T07:12:36.948807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.68918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T07:12:36.948851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.140479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T07:12:36.94891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.427754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-twmw9\" ","response":"range_response_count:1 size:7665"} {"level":"info","ts":"2026-04-16T07:12:36.948941Z","caller":"traceutil/trace.go:171","msg":"trace[828728841] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:3519; }","duration":"109.282043ms","start":"2026-04-16T07:12:36.839639Z","end":"2026-04-16T07:12:36.948921Z","steps":["trace[828728841] 'agreement among raft nodes before linearized reading' (duration: 109.102368ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:12:36.948964Z","caller":"traceutil/trace.go:171","msg":"trace[760287034] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-cf56b6468-twmw9; range_end:; response_count:1; response_revision:3519; }","duration":"208.530347ms","start":"2026-04-16T07:12:36.740409Z","end":"2026-04-16T07:12:36.948939Z","steps":["trace[760287034] 'agreement among raft nodes before linearized reading' (duration: 208.324212ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:12:36.948878Z","caller":"traceutil/trace.go:171","msg":"trace[669493904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3519; }","duration":"143.820373ms","start":"2026-04-16T07:12:36.805043Z","end":"2026-04-16T07:12:36.948863Z","steps":["trace[669493904] 'agreement among raft nodes before linearized reading' (duration: 143.70031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:12:36.948788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.640756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T07:12:36.949069Z","caller":"traceutil/trace.go:171","msg":"trace[1566896339] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:3519; }","duration":"233.971824ms","start":"2026-04-16T07:12:36.715059Z","end":"2026-04-16T07:12:36.949031Z","steps":["trace[1566896339] 'agreement among raft nodes before linearized reading' (duration: 233.636786ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:12:37.215434Z","caller":"traceutil/trace.go:171","msg":"trace[742780432] linearizableReadLoop","detail":"{readStateIndex:3681; appliedIndex:3680; }","duration":"212.788053ms","start":"2026-04-16T07:12:37.002629Z","end":"2026-04-16T07:12:37.215417Z","steps":["trace[742780432] 'read index received' (duration: 212.656648ms)","trace[742780432] 'applied index is now lower than readState.Index' (duration: 130.565µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T07:12:37.215655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.016979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:2 size:42804"} {"level":"info","ts":"2026-04-16T07:12:37.215611Z","caller":"traceutil/trace.go:171","msg":"trace[288853848] transaction","detail":"{read_only:false; response_revision:3520; number_of_response:1; }","duration":"255.888325ms","start":"2026-04-16T07:12:36.959676Z","end":"2026-04-16T07:12:37.215565Z","steps":["trace[288853848] 'process raft request' (duration: 255.646278ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:12:37.215707Z","caller":"traceutil/trace.go:171","msg":"trace[1967295307] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:2; response_revision:3520; }","duration":"213.130101ms","start":"2026-04-16T07:12:37.002562Z","end":"2026-04-16T07:12:37.215692Z","steps":["trace[1967295307] 'agreement among raft nodes before linearized reading' (duration: 212.929947ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:12:37.216134Z","caller":"traceutil/trace.go:171","msg":"trace[927385888] transaction","detail":"{read_only:false; response_revision:3521; number_of_response:1; }","duration":"117.6875ms","start":"2026-04-16T07:12:37.098422Z","end":"2026-04-16T07:12:37.21611Z","steps":["trace[927385888] 'process raft request' (duration: 117.402283ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:12:37.371656Z","caller":"traceutil/trace.go:171","msg":"trace[1232649723] transaction","detail":"{read_only:false; response_revision:3523; number_of_response:1; }","duration":"150.423586ms","start":"2026-04-16T07:12:37.221204Z","end":"2026-04-16T07:12:37.371628Z","steps":["trace[1232649723] 'process raft request' (duration: 146.680314ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:12:37.374374Z","caller":"traceutil/trace.go:171","msg":"trace[1419556262] transaction","detail":"{read_only:false; response_revision:3525; number_of_response:1; }","duration":"153.116722ms","start":"2026-04-16T07:12:37.221241Z","end":"2026-04-16T07:12:37.374357Z","steps":["trace[1419556262] 'process raft request' (duration: 153.02893ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:12:37.374403Z","caller":"traceutil/trace.go:171","msg":"trace[1407280406] transaction","detail":"{read_only:false; response_revision:3524; number_of_response:1; }","duration":"153.135022ms","start":"2026-04-16T07:12:37.221234Z","end":"2026-04-16T07:12:37.374369Z","steps":["trace[1407280406] 'process raft request' (duration: 152.946668ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:12:37.374709Z","caller":"traceutil/trace.go:171","msg":"trace[130126209] transaction","detail":"{read_only:false; response_revision:3526; number_of_response:1; }","duration":"153.379928ms","start":"2026-04-16T07:12:37.221315Z","end":"2026-04-16T07:12:37.374695Z","steps":["trace[130126209] 'process raft request' (duration: 153.004529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:12:37.375856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.95277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:2 size:42804"} {"level":"info","ts":"2026-04-16T07:12:37.375913Z","caller":"traceutil/trace.go:171","msg":"trace[1449124625] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:2; response_revision:3528; }","duration":"151.040021ms","start":"2026-04-16T07:12:37.224854Z","end":"2026-04-16T07:12:37.375894Z","steps":["trace[1449124625] 'agreement among raft nodes before linearized reading' (duration: 150.839717ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:12:37.375443Z","caller":"traceutil/trace.go:171","msg":"trace[898508376] linearizableReadLoop","detail":"{readStateIndex:3687; appliedIndex:3683; }","duration":"150.531899ms","start":"2026-04-16T07:12:37.22489Z","end":"2026-04-16T07:12:37.375421Z","steps":["trace[898508376] 'read index received' (duration: 143.125566ms)","trace[898508376] 'applied index is now lower than readState.Index' (duration: 7.373992ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:12:39.576341Z","caller":"traceutil/trace.go:171","msg":"trace[1470693705] transaction","detail":"{read_only:false; response_revision:3555; number_of_response:1; }","duration":"117.493405ms","start":"2026-04-16T07:12:39.458818Z","end":"2026-04-16T07:12:39.576311Z","steps":["trace[1470693705] 'process raft request' (duration: 117.28655ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:12:47.355599Z","caller":"traceutil/trace.go:171","msg":"trace[1611487560] transaction","detail":"{read_only:false; response_revision:3748; number_of_response:1; }","duration":"108.264116ms","start":"2026-04-16T07:12:47.2473Z","end":"2026-04-16T07:12:47.355564Z","steps":["trace[1611487560] 'process raft request' (duration: 108.01051ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:12:47.978673Z","caller":"traceutil/trace.go:171","msg":"trace[1792321808] transaction","detail":"{read_only:false; response_revision:3756; number_of_response:1; }","duration":"117.406233ms","start":"2026-04-16T07:12:47.861242Z","end":"2026-04-16T07:12:47.978648Z","steps":["trace[1792321808] 'process raft request' (duration: 117.27301ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:08.599943Z","caller":"traceutil/trace.go:171","msg":"trace[486409716] transaction","detail":"{read_only:false; response_revision:3877; number_of_response:1; }","duration":"122.711574ms","start":"2026-04-16T07:13:08.477154Z","end":"2026-04-16T07:13:08.599866Z","steps":["trace[486409716] 'process raft request' (duration: 23.131529ms)","trace[486409716] 'compare' (duration: 99.277927ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:13:08.608327Z","caller":"traceutil/trace.go:171","msg":"trace[1296994495] transaction","detail":"{read_only:false; response_revision:3879; number_of_response:1; }","duration":"130.350112ms","start":"2026-04-16T07:13:08.477957Z","end":"2026-04-16T07:13:08.608307Z","steps":["trace[1296994495] 'process raft request' (duration: 130.312841ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:08.608467Z","caller":"traceutil/trace.go:171","msg":"trace[881110233] transaction","detail":"{read_only:false; response_revision:3878; number_of_response:1; }","duration":"131.051789ms","start":"2026-04-16T07:13:08.477368Z","end":"2026-04-16T07:13:08.60842Z","steps":["trace[881110233] 'process raft request' (duration: 130.767372ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:08.64182Z","caller":"traceutil/trace.go:171","msg":"trace[387954519] transaction","detail":"{read_only:false; response_revision:3880; number_of_response:1; }","duration":"129.446461ms","start":"2026-04-16T07:13:08.512356Z","end":"2026-04-16T07:13:08.641803Z","steps":["trace[387954519] 'process raft request' (duration: 129.345618ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:09.602743Z","caller":"traceutil/trace.go:171","msg":"trace[766975183] transaction","detail":"{read_only:false; response_revision:3883; number_of_response:1; }","duration":"107.891708ms","start":"2026-04-16T07:13:09.494823Z","end":"2026-04-16T07:13:09.602714Z","steps":["trace[766975183] 'process raft request' (duration: 86.799548ms)","trace[766975183] 'compare' (duration: 20.918306ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:13:31.344019Z","caller":"traceutil/trace.go:171","msg":"trace[912664437] transaction","detail":"{read_only:false; response_revision:4553; number_of_response:1; }","duration":"101.618772ms","start":"2026-04-16T07:13:31.242372Z","end":"2026-04-16T07:13:31.343991Z","steps":["trace[912664437] 'process raft request' (duration: 86.986486ms)","trace[912664437] 'compare' (duration: 14.492243ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:13:31.370493Z","caller":"traceutil/trace.go:171","msg":"trace[1854858063] transaction","detail":"{read_only:false; response_revision:4554; number_of_response:1; }","duration":"103.875646ms","start":"2026-04-16T07:13:31.266581Z","end":"2026-04-16T07:13:31.370457Z","steps":["trace[1854858063] 'process raft request' (duration: 103.705782ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:31.370527Z","caller":"traceutil/trace.go:171","msg":"trace[644895299] transaction","detail":"{read_only:false; response_revision:4555; number_of_response:1; }","duration":"103.023505ms","start":"2026-04-16T07:13:31.26748Z","end":"2026-04-16T07:13:31.370503Z","steps":["trace[644895299] 'process raft request' (duration: 102.899992ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:31.370521Z","caller":"traceutil/trace.go:171","msg":"trace[422161916] linearizableReadLoop","detail":"{readStateIndex:4765; appliedIndex:4762; }","duration":"102.916793ms","start":"2026-04-16T07:13:31.267579Z","end":"2026-04-16T07:13:31.370496Z","steps":["trace[422161916] 'read index received' (duration: 61.797463ms)","trace[422161916] 'applied index is now lower than readState.Index' (duration: 41.11863ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T07:13:31.370843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.244231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129807"} {"level":"info","ts":"2026-04-16T07:13:31.370869Z","caller":"traceutil/trace.go:171","msg":"trace[392009632] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4557; }","duration":"103.299022ms","start":"2026-04-16T07:13:31.267562Z","end":"2026-04-16T07:13:31.370861Z","steps":["trace[392009632] 'agreement among raft nodes before linearized reading' (duration: 103.117898ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:31.673802Z","caller":"traceutil/trace.go:171","msg":"trace[1974675909] transaction","detail":"{read_only:false; response_revision:4560; number_of_response:1; }","duration":"227.154293ms","start":"2026-04-16T07:13:31.446613Z","end":"2026-04-16T07:13:31.673767Z","steps":["trace[1974675909] 'process raft request' (duration: 226.916608ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:31.681241Z","caller":"traceutil/trace.go:171","msg":"trace[1391728919] linearizableReadLoop","detail":"{readStateIndex:4771; appliedIndex:4769; }","duration":"234.087203ms","start":"2026-04-16T07:13:31.44713Z","end":"2026-04-16T07:13:31.681218Z","steps":["trace[1391728919] 'read index received' (duration: 226.469507ms)","trace[1391728919] 'applied index is now lower than readState.Index' (duration: 7.614896ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:13:31.681387Z","caller":"traceutil/trace.go:171","msg":"trace[2089370148] transaction","detail":"{read_only:false; response_revision:4561; number_of_response:1; }","duration":"234.572224ms","start":"2026-04-16T07:13:31.446769Z","end":"2026-04-16T07:13:31.681341Z","steps":["trace[2089370148] 'process raft request' (duration: 234.202235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:13:31.681435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.279797ms","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-16T07:13:31.681493Z","caller":"traceutil/trace.go:171","msg":"trace[1461353658] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4562; }","duration":"234.383689ms","start":"2026-04-16T07:13:31.447095Z","end":"2026-04-16T07:13:31.681479Z","steps":["trace[1461353658] 'agreement among raft nodes before linearized reading' (duration: 234.258146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:13:31.681523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.742455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fed7cf50-5880-40ed-a281-55fb05bdecdb\" ","response":"range_response_count:1 size:3616"} {"level":"info","ts":"2026-04-16T07:13:31.681561Z","caller":"traceutil/trace.go:171","msg":"trace[1524387918] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fed7cf50-5880-40ed-a281-55fb05bdecdb; range_end:; response_count:1; response_revision:4562; }","duration":"222.851688ms","start":"2026-04-16T07:13:31.4587Z","end":"2026-04-16T07:13:31.681552Z","steps":["trace[1524387918] 'agreement among raft nodes before linearized reading' (duration: 222.675014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:13:31.681605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.324931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-04-16T07:13:31.681681Z","caller":"traceutil/trace.go:171","msg":"trace[1537658027] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4562; }","duration":"105.469465ms","start":"2026-04-16T07:13:31.576194Z","end":"2026-04-16T07:13:31.681663Z","steps":["trace[1537658027] 'agreement among raft nodes before linearized reading' (duration: 105.240359ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:31.798608Z","caller":"traceutil/trace.go:171","msg":"trace[1288917900] transaction","detail":"{read_only:false; response_revision:4564; number_of_response:1; }","duration":"100.423313ms","start":"2026-04-16T07:13:31.698145Z","end":"2026-04-16T07:13:31.798568Z","steps":["trace[1288917900] 'process raft request' (duration: 71.657823ms)","trace[1288917900] 'compare' (duration: 28.487193ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:13:32.018035Z","caller":"traceutil/trace.go:171","msg":"trace[1270299159] transaction","detail":"{read_only:false; response_revision:4567; number_of_response:1; }","duration":"143.311165ms","start":"2026-04-16T07:13:31.874702Z","end":"2026-04-16T07:13:32.018013Z","steps":["trace[1270299159] 'process raft request' (duration: 124.728274ms)","trace[1270299159] 'compare' (duration: 18.453768ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:13:32.097885Z","caller":"traceutil/trace.go:171","msg":"trace[2092006839] linearizableReadLoop","detail":"{readStateIndex:4778; appliedIndex:4776; }","duration":"180.768806ms","start":"2026-04-16T07:13:31.917095Z","end":"2026-04-16T07:13:32.097864Z","steps":["trace[2092006839] 'read index received' (duration: 82.350853ms)","trace[2092006839] 'applied index is now lower than readState.Index' (duration: 98.417203ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:13:32.098096Z","caller":"traceutil/trace.go:171","msg":"trace[621046852] transaction","detail":"{read_only:false; response_revision:4568; number_of_response:1; }","duration":"217.293945ms","start":"2026-04-16T07:13:31.880795Z","end":"2026-04-16T07:13:32.098089Z","steps":["trace[621046852] 'process raft request' (duration: 216.895285ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:32.098329Z","caller":"traceutil/trace.go:171","msg":"trace[437266932] transaction","detail":"{read_only:false; response_revision:4569; number_of_response:1; }","duration":"159.212061ms","start":"2026-04-16T07:13:31.939109Z","end":"2026-04-16T07:13:32.098321Z","steps":["trace[437266932] 'process raft request' (duration: 158.696739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:13:32.098519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.407832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q8kzg\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-04-16T07:13:32.098547Z","caller":"traceutil/trace.go:171","msg":"trace[1464005199] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q8kzg; range_end:; response_count:1; response_revision:4569; }","duration":"181.476794ms","start":"2026-04-16T07:13:31.917062Z","end":"2026-04-16T07:13:32.098539Z","steps":["trace[1464005199] 'agreement among raft nodes before linearized reading' (duration: 181.351721ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:32.459484Z","caller":"traceutil/trace.go:171","msg":"trace[1743403483] linearizableReadLoop","detail":"{readStateIndex:4780; appliedIndex:4779; }","duration":"361.510931ms","start":"2026-04-16T07:13:32.097945Z","end":"2026-04-16T07:13:32.459456Z","steps":["trace[1743403483] 'read index received' (duration: 361.405168ms)","trace[1743403483] 'applied index is now lower than readState.Index' (duration: 104.403µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:13:32.459644Z","caller":"traceutil/trace.go:171","msg":"trace[1583958736] transaction","detail":"{read_only:false; response_revision:4570; number_of_response:1; }","duration":"419.096821ms","start":"2026-04-16T07:13:32.040505Z","end":"2026-04-16T07:13:32.459601Z","steps":["trace[1583958736] 'process raft request' (duration: 418.802944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:13:32.459835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T07:13:32.040478Z","time spent":"419.265546ms","remote":"127.0.0.1:43198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2228,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T07:13:32.459927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.712093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:16 size:470261"} {"level":"info","ts":"2026-04-16T07:13:32.459965Z","caller":"traceutil/trace.go:171","msg":"trace[43882409] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:16; response_revision:4570; }","duration":"430.811116ms","start":"2026-04-16T07:13:32.029144Z","end":"2026-04-16T07:13:32.459955Z","steps":["trace[43882409] 'agreement among raft nodes before linearized reading' (duration: 430.440827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:13:32.460026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T07:13:32.029121Z","time spent":"430.895058ms","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":16,"response size":470285,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-04-16T07:13:32.781935Z","caller":"traceutil/trace.go:171","msg":"trace[1692026313] linearizableReadLoop","detail":"{readStateIndex:4781; appliedIndex:4780; }","duration":"322.355198ms","start":"2026-04-16T07:13:32.459553Z","end":"2026-04-16T07:13:32.781908Z","steps":["trace[1692026313] 'read index received' (duration: 322.176924ms)","trace[1692026313] 'applied index is now lower than readState.Index' (duration: 177.464µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T07:13:32.78227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.10333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6a62bf8d-004f-432a-99a2-4b8bafa64551\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-16T07:13:32.782345Z","caller":"traceutil/trace.go:171","msg":"trace[1618612584] transaction","detail":"{read_only:false; response_revision:4571; number_of_response:1; }","duration":"674.651085ms","start":"2026-04-16T07:13:32.107674Z","end":"2026-04-16T07:13:32.782325Z","steps":["trace[1618612584] 'process raft request' (duration: 674.104302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:13:32.782454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T07:13:32.107656Z","time spent":"674.747027ms","remote":"127.0.0.1:43198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3409,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T07:13:32.782355Z","caller":"traceutil/trace.go:171","msg":"trace[1186307142] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6a62bf8d-004f-432a-99a2-4b8bafa64551; range_end:; response_count:1; response_revision:4571; }","duration":"611.249343ms","start":"2026-04-16T07:13:32.17109Z","end":"2026-04-16T07:13:32.782339Z","steps":["trace[1186307142] 'agreement among raft nodes before linearized reading' (duration: 611.046318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:13:32.782601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T07:13:32.171069Z","time spent":"611.460598ms","remote":"127.0.0.1:32868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6a62bf8d-004f-432a-99a2-4b8bafa64551\" "} {"level":"warn","ts":"2026-04-16T07:13:32.782675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.764455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:10 size:84351"} {"level":"info","ts":"2026-04-16T07:13:32.782759Z","caller":"traceutil/trace.go:171","msg":"trace[689029454] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:10; response_revision:4571; }","duration":"678.886037ms","start":"2026-04-16T07:13:32.103855Z","end":"2026-04-16T07:13:32.782741Z","steps":["trace[689029454] 'agreement among raft nodes before linearized reading' (duration: 678.512198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:13:32.782811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T07:13:32.10384Z","time spent":"678.961959ms","remote":"127.0.0.1:32868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":10,"response size":84375,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-04-16T07:13:32.782265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.239823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fed7cf50-5880-40ed-a281-55fb05bdecdb\" ","response":"range_response_count:1 size:3616"} {"level":"info","ts":"2026-04-16T07:13:32.782889Z","caller":"traceutil/trace.go:171","msg":"trace[1188508623] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fed7cf50-5880-40ed-a281-55fb05bdecdb; range_end:; response_count:1; response_revision:4571; }","duration":"609.91407ms","start":"2026-04-16T07:13:32.172957Z","end":"2026-04-16T07:13:32.782871Z","steps":["trace[1188508623] 'agreement among raft nodes before linearized reading' (duration: 609.09976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:13:32.78294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T07:13:32.172949Z","time spent":"609.979202ms","remote":"127.0.0.1:32868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3640,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fed7cf50-5880-40ed-a281-55fb05bdecdb\" "} {"level":"warn","ts":"2026-04-16T07:13:32.783063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.786944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10328"} {"level":"info","ts":"2026-04-16T07:13:32.783142Z","caller":"traceutil/trace.go:171","msg":"trace[1655621906] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4571; }","duration":"256.897816ms","start":"2026-04-16T07:13:32.526223Z","end":"2026-04-16T07:13:32.783121Z","steps":["trace[1655621906] 'agreement among raft nodes before linearized reading' (duration: 256.697071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:13:32.783141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.111623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T07:13:32.783241Z","caller":"traceutil/trace.go:171","msg":"trace[668976287] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:4571; }","duration":"663.261117ms","start":"2026-04-16T07:13:32.119959Z","end":"2026-04-16T07:13:32.78322Z","steps":["trace[668976287] 'agreement among raft nodes before linearized reading' (duration: 663.079233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:13:32.783292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T07:13:32.119938Z","time spent":"663.343229ms","remote":"127.0.0.1:33032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-16T07:13:32.78323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.453913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-16T07:13:32.783416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.117424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:16 size:470261"} {"level":"info","ts":"2026-04-16T07:13:32.783453Z","caller":"traceutil/trace.go:171","msg":"trace[254055987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4571; }","duration":"159.023956ms","start":"2026-04-16T07:13:32.624409Z","end":"2026-04-16T07:13:32.783433Z","steps":["trace[254055987] 'agreement among raft nodes before linearized reading' (duration: 158.311229ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:32.783505Z","caller":"traceutil/trace.go:171","msg":"trace[376587039] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:16; response_revision:4571; }","duration":"306.261016ms","start":"2026-04-16T07:13:32.477225Z","end":"2026-04-16T07:13:32.783486Z","steps":["trace[376587039] 'agreement among raft nodes before linearized reading' (duration: 305.234002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:13:32.783557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T07:13:32.477208Z","time spent":"306.33491ms","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":16,"response size":470285,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-04-16T07:13:32.907054Z","caller":"traceutil/trace.go:171","msg":"trace[1288408585] transaction","detail":"{read_only:false; response_revision:4572; number_of_response:1; }","duration":"118.38964ms","start":"2026-04-16T07:13:32.788627Z","end":"2026-04-16T07:13:32.907016Z","steps":["trace[1288408585] 'process raft request' (duration: 87.466328ms)","trace[1288408585] 'compare' (duration: 30.528551ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:13:32.955999Z","caller":"traceutil/trace.go:171","msg":"trace[1239561666] transaction","detail":"{read_only:false; response_revision:4573; number_of_response:1; }","duration":"166.852238ms","start":"2026-04-16T07:13:32.789118Z","end":"2026-04-16T07:13:32.95597Z","steps":["trace[1239561666] 'process raft request' (duration: 166.620342ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:32.956337Z","caller":"traceutil/trace.go:171","msg":"trace[352975101] transaction","detail":"{read_only:false; response_revision:4574; number_of_response:1; }","duration":"166.920128ms","start":"2026-04-16T07:13:32.789398Z","end":"2026-04-16T07:13:32.956318Z","steps":["trace[352975101] 'process raft request' (duration: 166.523718ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:32.956495Z","caller":"traceutil/trace.go:171","msg":"trace[1920189541] transaction","detail":"{read_only:false; response_revision:4576; number_of_response:1; }","duration":"165.599246ms","start":"2026-04-16T07:13:32.790882Z","end":"2026-04-16T07:13:32.956482Z","steps":["trace[1920189541] 'process raft request' (duration: 165.453332ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:32.956484Z","caller":"traceutil/trace.go:171","msg":"trace[527194073] transaction","detail":"{read_only:false; response_revision:4577; number_of_response:1; }","duration":"163.461094ms","start":"2026-04-16T07:13:32.792989Z","end":"2026-04-16T07:13:32.95645Z","steps":["trace[527194073] 'process raft request' (duration: 163.398323ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:32.956702Z","caller":"traceutil/trace.go:171","msg":"trace[1543318979] transaction","detail":"{read_only:false; response_revision:4575; number_of_response:1; }","duration":"166.871457ms","start":"2026-04-16T07:13:32.789678Z","end":"2026-04-16T07:13:32.95655Z","steps":["trace[1543318979] 'process raft request' (duration: 166.469607ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:33.249396Z","caller":"traceutil/trace.go:171","msg":"trace[1766382926] linearizableReadLoop","detail":"{readStateIndex:4788; appliedIndex:4787; }","duration":"283.084973ms","start":"2026-04-16T07:13:32.966295Z","end":"2026-04-16T07:13:33.24938Z","steps":["trace[1766382926] 'read index received' (duration: 282.987751ms)","trace[1766382926] 'applied index is now lower than readState.Index' (duration: 96.292µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:13:33.249479Z","caller":"traceutil/trace.go:171","msg":"trace[303326677] transaction","detail":"{read_only:false; response_revision:4578; number_of_response:1; }","duration":"286.754554ms","start":"2026-04-16T07:13:32.962677Z","end":"2026-04-16T07:13:33.249432Z","steps":["trace[303326677] 'process raft request' (duration: 286.58354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:13:33.249596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.260847ms","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-16T07:13:33.24972Z","caller":"traceutil/trace.go:171","msg":"trace[33616146] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4578; }","duration":"283.441482ms","start":"2026-04-16T07:13:32.966257Z","end":"2026-04-16T07:13:33.249699Z","steps":["trace[33616146] 'agreement among raft nodes before linearized reading' (duration: 283.193856ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:33.355101Z","caller":"traceutil/trace.go:171","msg":"trace[1323520022] transaction","detail":"{read_only:false; response_revision:4579; number_of_response:1; }","duration":"386.060358ms","start":"2026-04-16T07:13:32.969002Z","end":"2026-04-16T07:13:33.355062Z","steps":["trace[1323520022] 'process raft request' (duration: 373.692616ms)","trace[1323520022] 'compare' (duration: 12.117445ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:13:33.355125Z","caller":"traceutil/trace.go:171","msg":"trace[1350961032] transaction","detail":"{read_only:false; response_revision:4580; number_of_response:1; }","duration":"329.385899ms","start":"2026-04-16T07:13:33.025709Z","end":"2026-04-16T07:13:33.355095Z","steps":["trace[1350961032] 'process raft request' (duration: 329.241465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:13:33.355246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.531124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T07:13:33.355291Z","caller":"traceutil/trace.go:171","msg":"trace[1915303437] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4582; }","duration":"102.634516ms","start":"2026-04-16T07:13:33.252645Z","end":"2026-04-16T07:13:33.355279Z","steps":["trace[1915303437] 'agreement among raft nodes before linearized reading' (duration: 102.541654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:13:33.355284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T07:13:32.968968Z","time spent":"386.226372ms","remote":"127.0.0.1:32868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6131,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T07:13:33.355337Z","caller":"traceutil/trace.go:171","msg":"trace[1616997595] transaction","detail":"{read_only:false; response_revision:4582; number_of_response:1; }","duration":"158.161516ms","start":"2026-04-16T07:13:33.197169Z","end":"2026-04-16T07:13:33.355331Z","steps":["trace[1616997595] 'process raft request' (duration: 157.89454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:13:33.355353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T07:13:33.025679Z","time spent":"329.532663ms","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":646,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T07:13:33.355291Z","caller":"traceutil/trace.go:171","msg":"trace[873602842] transaction","detail":"{read_only:false; response_revision:4581; number_of_response:1; }","duration":"321.836896ms","start":"2026-04-16T07:13:33.033441Z","end":"2026-04-16T07:13:33.355278Z","steps":["trace[873602842] 'process raft request' (duration: 321.568069ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:33.355148Z","caller":"traceutil/trace.go:171","msg":"trace[1517946710] linearizableReadLoop","detail":"{readStateIndex:4792; appliedIndex:4788; }","duration":"102.424371ms","start":"2026-04-16T07:13:33.252698Z","end":"2026-04-16T07:13:33.355122Z","steps":["trace[1517946710] 'read index received' (duration: 89.938967ms)","trace[1517946710] 'applied index is now lower than readState.Index' (duration: 12.484284ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T07:13:33.355544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T07:13:33.03341Z","time spent":"322.01552ms","remote":"127.0.0.1:57718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1081,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T07:13:44.14607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.983192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-16T07:13:44.146181Z","caller":"traceutil/trace.go:171","msg":"trace[105660356] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:4733; }","duration":"297.129875ms","start":"2026-04-16T07:13:43.849021Z","end":"2026-04-16T07:13:44.146151Z","steps":["trace[105660356] 'range keys from in-memory index tree' (duration: 296.759196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:13:44.14609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.243964ms","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":"warn","ts":"2026-04-16T07:13:44.146381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.642216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T07:13:44.14641Z","caller":"traceutil/trace.go:171","msg":"trace[1434456177] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4734; }","duration":"211.694138ms","start":"2026-04-16T07:13:43.934709Z","end":"2026-04-16T07:13:44.146403Z","steps":["trace[1434456177] 'agreement among raft nodes before linearized reading' (duration: 211.617676ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:44.146403Z","caller":"traceutil/trace.go:171","msg":"trace[819341481] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"277.792646ms","start":"2026-04-16T07:13:43.868561Z","end":"2026-04-16T07:13:44.146354Z","steps":["trace[819341481] 'process raft request' (duration: 242.828175ms)","trace[819341481] 'compare' (duration: 34.389716ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:13:44.146403Z","caller":"traceutil/trace.go:171","msg":"trace[1965927038] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4733; }","duration":"280.600413ms","start":"2026-04-16T07:13:43.865779Z","end":"2026-04-16T07:13:44.146379Z","steps":["trace[1965927038] 'range keys from in-memory index tree' (duration: 280.077651ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:13:44.146303Z","caller":"traceutil/trace.go:171","msg":"trace[1076334988] linearizableReadLoop","detail":"{readStateIndex:4948; appliedIndex:4947; }","duration":"211.552664ms","start":"2026-04-16T07:13:43.934735Z","end":"2026-04-16T07:13:44.146288Z","steps":["trace[1076334988] 'read index received' (duration: 176.604884ms)","trace[1076334988] 'applied index is now lower than readState.Index' (duration: 34.94694ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T07:13:44.146718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.139867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T07:13:44.146767Z","caller":"traceutil/trace.go:171","msg":"trace[1437672219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4734; }","duration":"200.22713ms","start":"2026-04-16T07:13:43.946525Z","end":"2026-04-16T07:13:44.146752Z","steps":["trace[1437672219] 'agreement among raft nodes before linearized reading' (duration: 200.145408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:14:21.312658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.273948ms","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-04-16T07:14:21.312605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.052197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T07:14:21.3128Z","caller":"traceutil/trace.go:171","msg":"trace[1757395025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4981; }","duration":"101.305623ms","start":"2026-04-16T07:14:21.211468Z","end":"2026-04-16T07:14:21.312774Z","steps":["trace[1757395025] 'range keys from in-memory index tree' (duration: 100.829982ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:14:21.312728Z","caller":"traceutil/trace.go:171","msg":"trace[13791365] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4981; }","duration":"116.391801ms","start":"2026-04-16T07:14:21.196322Z","end":"2026-04-16T07:14:21.312714Z","steps":["trace[13791365] 'range keys from in-memory index tree' (duration: 116.098723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:14:21.313165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.540484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-16T07:14:21.313258Z","caller":"traceutil/trace.go:171","msg":"trace[1534852806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4981; }","duration":"102.685317ms","start":"2026-04-16T07:14:21.210555Z","end":"2026-04-16T07:14:21.31324Z","steps":["trace[1534852806] 'range keys from in-memory index tree' (duration: 102.100123ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:14:21.879953Z","caller":"traceutil/trace.go:171","msg":"trace[1592824926] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"203.031937ms","start":"2026-04-16T07:14:21.676889Z","end":"2026-04-16T07:14:21.879921Z","steps":["trace[1592824926] 'process raft request' (duration: 122.779146ms)","trace[1592824926] 'compare' (duration: 80.077626ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:14:58.515244Z","caller":"traceutil/trace.go:171","msg":"trace[959573214] transaction","detail":"{read_only:false; response_revision:5246; number_of_response:1; }","duration":"125.934222ms","start":"2026-04-16T07:14:58.389289Z","end":"2026-04-16T07:14:58.515224Z","steps":["trace[959573214] 'process raft request' (duration: 125.796139ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:14:58.517096Z","caller":"traceutil/trace.go:171","msg":"trace[1982696163] transaction","detail":"{read_only:false; response_revision:5247; number_of_response:1; }","duration":"125.349148ms","start":"2026-04-16T07:14:58.391682Z","end":"2026-04-16T07:14:58.517031Z","steps":["trace[1982696163] 'process raft request' (duration: 125.081341ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:16:10.92226Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2650} {"level":"info","ts":"2026-04-16T07:16:10.94354Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2650,"took":"20.209328ms","hash":3405503942,"current-db-size-bytes":23576576,"current-db-size":"24 MB","current-db-size-in-use-bytes":22921216,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-16T07:16:10.943674Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3405503942,"revision":2650,"compact-revision":1664} {"level":"info","ts":"2026-04-16T07:19:19.641118Z","caller":"traceutil/trace.go:171","msg":"trace[247880518] transaction","detail":"{read_only:false; response_revision:7632; number_of_response:1; }","duration":"103.944665ms","start":"2026-04-16T07:19:19.537137Z","end":"2026-04-16T07:19:19.641081Z","steps":["trace[247880518] 'process raft request' (duration: 103.777521ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:19:19.649358Z","caller":"traceutil/trace.go:171","msg":"trace[1030087776] transaction","detail":"{read_only:false; response_revision:7633; number_of_response:1; }","duration":"107.021449ms","start":"2026-04-16T07:19:19.542317Z","end":"2026-04-16T07:19:19.649338Z","steps":["trace[1030087776] 'process raft request' (duration: 106.868265ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:19:26.612644Z","caller":"traceutil/trace.go:171","msg":"trace[613196193] transaction","detail":"{read_only:false; response_revision:7740; number_of_response:1; }","duration":"116.490167ms","start":"2026-04-16T07:19:26.496124Z","end":"2026-04-16T07:19:26.612615Z","steps":["trace[613196193] 'process raft request' (duration: 116.313383ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:19:26.637967Z","caller":"traceutil/trace.go:171","msg":"trace[511094089] transaction","detail":"{read_only:false; response_revision:7741; number_of_response:1; }","duration":"118.049214ms","start":"2026-04-16T07:19:26.519877Z","end":"2026-04-16T07:19:26.637926Z","steps":["trace[511094089] 'process raft request' (duration: 117.871ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:20:03.027731Z","caller":"traceutil/trace.go:171","msg":"trace[1213670798] transaction","detail":"{read_only:false; response_revision:8022; number_of_response:1; }","duration":"110.5429ms","start":"2026-04-16T07:20:02.917156Z","end":"2026-04-16T07:20:03.027699Z","steps":["trace[1213670798] 'process raft request' (duration: 110.326175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:20:03.181484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.086516ms","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-16T07:20:03.181588Z","caller":"traceutil/trace.go:171","msg":"trace[807918733] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8024; }","duration":"142.220209ms","start":"2026-04-16T07:20:03.039342Z","end":"2026-04-16T07:20:03.181562Z","steps":["trace[807918733] 'agreement among raft nodes before linearized reading' (duration: 141.950623ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:21:10.932819Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5640} {"level":"info","ts":"2026-04-16T07:21:11.014674Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5640,"took":"79.932389ms","hash":3798667464,"current-db-size-bytes":47415296,"current-db-size":"47 MB","current-db-size-in-use-bytes":35184640,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-04-16T07:21:11.014738Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3798667464,"revision":5640,"compact-revision":2650} {"level":"info","ts":"2026-04-16T07:21:34.345861Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000253e.wal"} {"level":"info","ts":"2026-04-16T07:21:47.112635Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":10006,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-16T07:21:47.125501Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10006} {"level":"info","ts":"2026-04-16T07:21:47.126406Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5006} {"level":"info","ts":"2026-04-16T07:21:59.319962Z","caller":"traceutil/trace.go:171","msg":"trace[1718416571] linearizableReadLoop","detail":"{readStateIndex:10345; appliedIndex:10344; }","duration":"100.408024ms","start":"2026-04-16T07:21:59.21952Z","end":"2026-04-16T07:21:59.319928Z","steps":["trace[1718416571] 'read index received' (duration: 99.882211ms)","trace[1718416571] 'applied index is now lower than readState.Index' (duration: 524.033µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:21:59.320018Z","caller":"traceutil/trace.go:171","msg":"trace[1521505843] transaction","detail":"{read_only:false; response_revision:9973; number_of_response:1; }","duration":"108.213021ms","start":"2026-04-16T07:21:59.211771Z","end":"2026-04-16T07:21:59.319984Z","steps":["trace[1521505843] 'process raft request' (duration: 107.848862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:21:59.32029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.752883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-db-sync-vqh4k\" ","response":"range_response_count:1 size:6716"} {"level":"info","ts":"2026-04-16T07:21:59.320334Z","caller":"traceutil/trace.go:171","msg":"trace[335793375] range","detail":"{range_begin:/registry/pods/openstack/heat-db-sync-vqh4k; range_end:; response_count:1; response_revision:9973; }","duration":"100.850965ms","start":"2026-04-16T07:21:59.219475Z","end":"2026-04-16T07:21:59.320326Z","steps":["trace[335793375] 'agreement among raft nodes before linearized reading' (duration: 100.61426ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:21:59.591011Z","caller":"traceutil/trace.go:171","msg":"trace[1033196987] transaction","detail":"{read_only:false; response_revision:9976; number_of_response:1; }","duration":"251.346124ms","start":"2026-04-16T07:21:59.339621Z","end":"2026-04-16T07:21:59.590967Z","steps":["trace[1033196987] 'process raft request' (duration: 250.676968ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:21:59.632286Z","caller":"traceutil/trace.go:171","msg":"trace[363281203] transaction","detail":"{read_only:false; response_revision:9977; number_of_response:1; }","duration":"154.073625ms","start":"2026-04-16T07:21:59.478174Z","end":"2026-04-16T07:21:59.632247Z","steps":["trace[363281203] 'process raft request' (duration: 153.737717ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:21:59.632277Z","caller":"traceutil/trace.go:171","msg":"trace[418724889] linearizableReadLoop","detail":"{readStateIndex:10350; appliedIndex:10347; }","duration":"140.634202ms","start":"2026-04-16T07:21:59.491572Z","end":"2026-04-16T07:21:59.632206Z","steps":["trace[418724889] 'read index received' (duration: 98.822616ms)","trace[418724889] 'applied index is now lower than readState.Index' (duration: 41.809646ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T07:21:59.662957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.36392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-16T07:21:59.663057Z","caller":"traceutil/trace.go:171","msg":"trace[1603542660] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9977; }","duration":"171.522004ms","start":"2026-04-16T07:21:59.491506Z","end":"2026-04-16T07:21:59.663028Z","steps":["trace[1603542660] 'agreement among raft nodes before linearized reading' (duration: 140.820276ms)","trace[1603542660] 'range keys from in-memory index tree' (duration: 30.480813ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:22:02.367322Z","caller":"traceutil/trace.go:171","msg":"trace[1286764609] transaction","detail":"{read_only:false; response_revision:10027; number_of_response:1; }","duration":"101.325337ms","start":"2026-04-16T07:22:02.26596Z","end":"2026-04-16T07:22:02.367286Z","steps":["trace[1286764609] 'process raft request' (duration: 101.130942ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:22:21.019922Z","caller":"traceutil/trace.go:171","msg":"trace[1625899448] linearizableReadLoop","detail":"{readStateIndex:10599; appliedIndex:10598; }","duration":"137.927966ms","start":"2026-04-16T07:22:20.881964Z","end":"2026-04-16T07:22:21.019892Z","steps":["trace[1625899448] 'read index received' (duration: 137.721451ms)","trace[1625899448] 'applied index is now lower than readState.Index' (duration: 205.335µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:22:21.020088Z","caller":"traceutil/trace.go:171","msg":"trace[1598195771] transaction","detail":"{read_only:false; response_revision:10221; number_of_response:1; }","duration":"141.088953ms","start":"2026-04-16T07:22:20.878959Z","end":"2026-04-16T07:22:21.020048Z","steps":["trace[1598195771] 'process raft request' (duration: 140.757885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:22:21.020175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.185093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T07:22:21.020263Z","caller":"traceutil/trace.go:171","msg":"trace[107270245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10221; }","duration":"138.284575ms","start":"2026-04-16T07:22:20.881924Z","end":"2026-04-16T07:22:21.020208Z","steps":["trace[107270245] 'agreement among raft nodes before linearized reading' (duration: 138.08755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:22:21.150972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.050001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T07:22:21.151156Z","caller":"traceutil/trace.go:171","msg":"trace[1043690478] transaction","detail":"{read_only:false; response_revision:10222; number_of_response:1; }","duration":"126.177014ms","start":"2026-04-16T07:22:21.024945Z","end":"2026-04-16T07:22:21.151122Z","steps":["trace[1043690478] 'process raft request' (duration: 11.887567ms)","trace[1043690478] 'compare' (duration: 113.893767ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:26:10.939237Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8625} {"level":"info","ts":"2026-04-16T07:26:11.043776Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8625,"took":"101.888566ms","hash":821142216,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":36638720,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-16T07:26:11.043845Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":821142216,"revision":8625,"compact-revision":5640} {"level":"info","ts":"2026-04-16T07:27:18.907427Z","caller":"traceutil/trace.go:171","msg":"trace[746508153] transaction","detail":"{read_only:false; number_of_response:0; response_revision:13280; }","duration":"237.162356ms","start":"2026-04-16T07:27:18.670234Z","end":"2026-04-16T07:27:18.907396Z","steps":["trace[746508153] 'process raft request' (duration: 237.042573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:18.907605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.803541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T07:27:18.907428Z","caller":"traceutil/trace.go:171","msg":"trace[1614979358] linearizableReadLoop","detail":"{readStateIndex:13743; appliedIndex:13743; }","duration":"149.614357ms","start":"2026-04-16T07:27:18.757783Z","end":"2026-04-16T07:27:18.907397Z","steps":["trace[1614979358] 'read index received' (duration: 149.603537ms)","trace[1614979358] 'applied index is now lower than readState.Index' (duration: 8.68µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:27:18.907679Z","caller":"traceutil/trace.go:171","msg":"trace[1217727099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13280; }","duration":"149.891363ms","start":"2026-04-16T07:27:18.757775Z","end":"2026-04-16T07:27:18.907666Z","steps":["trace[1217727099] 'agreement among raft nodes before linearized reading' (duration: 149.667488ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:27:18.951961Z","caller":"traceutil/trace.go:171","msg":"trace[1741410893] transaction","detail":"{read_only:false; response_revision:13281; number_of_response:1; }","duration":"120.9166ms","start":"2026-04-16T07:27:18.830895Z","end":"2026-04-16T07:27:18.951811Z","steps":["trace[1741410893] 'process raft request' (duration: 120.659504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:18.952039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.870633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T07:27:18.952124Z","caller":"traceutil/trace.go:171","msg":"trace[266646778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13281; }","duration":"140.011336ms","start":"2026-04-16T07:27:18.81209Z","end":"2026-04-16T07:27:18.952102Z","steps":["trace[266646778] 'agreement among raft nodes before linearized reading' (duration: 139.894153ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:27:19.131289Z","caller":"traceutil/trace.go:171","msg":"trace[597932020] linearizableReadLoop","detail":"{readStateIndex:13748; appliedIndex:13747; }","duration":"110.041728ms","start":"2026-04-16T07:27:19.021206Z","end":"2026-04-16T07:27:19.131248Z","steps":["trace[597932020] 'read index received' (duration: 109.556786ms)","trace[597932020] 'applied index is now lower than readState.Index' (duration: 483.582µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:27:19.13148Z","caller":"traceutil/trace.go:171","msg":"trace[809493518] transaction","detail":"{read_only:false; response_revision:13285; number_of_response:1; }","duration":"120.508559ms","start":"2026-04-16T07:27:19.010933Z","end":"2026-04-16T07:27:19.131442Z","steps":["trace[809493518] 'process raft request' (duration: 120.017927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:19.131695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.166135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q8kzg\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-04-16T07:27:19.13177Z","caller":"traceutil/trace.go:171","msg":"trace[2125715878] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q8kzg; range_end:; response_count:1; response_revision:13285; }","duration":"111.315069ms","start":"2026-04-16T07:27:19.02043Z","end":"2026-04-16T07:27:19.131746Z","steps":["trace[2125715878] 'agreement among raft nodes before linearized reading' (duration: 111.016252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:23.092014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.401015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T07:27:23.092108Z","caller":"traceutil/trace.go:171","msg":"trace[270054649] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:0; response_revision:13572; }","duration":"180.512038ms","start":"2026-04-16T07:27:22.911575Z","end":"2026-04-16T07:27:23.092087Z","steps":["trace[270054649] 'range keys from in-memory index tree' (duration: 180.352843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:23.092342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.929598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T07:27:23.092363Z","caller":"traceutil/trace.go:171","msg":"trace[1217234942] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:13572; }","duration":"180.995009ms","start":"2026-04-16T07:27:22.911361Z","end":"2026-04-16T07:27:23.092356Z","steps":["trace[1217234942] 'range keys from in-memory index tree' (duration: 180.866666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:23.092458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.698317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-04-16T07:27:23.092536Z","caller":"traceutil/trace.go:171","msg":"trace[567891667] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:13572; }","duration":"179.80801ms","start":"2026-04-16T07:27:22.912712Z","end":"2026-04-16T07:27:23.09252Z","steps":["trace[567891667] 'range keys from in-memory index tree' (duration: 179.592985ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:27:23.092523Z","caller":"traceutil/trace.go:171","msg":"trace[1773749320] transaction","detail":"{read_only:false; response_revision:13573; number_of_response:1; }","duration":"117.64574ms","start":"2026-04-16T07:27:22.974845Z","end":"2026-04-16T07:27:23.09249Z","steps":["trace[1773749320] 'process raft request' (duration: 60.893239ms)","trace[1773749320] 'compare' (duration: 56.569477ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:27:23.092515Z","caller":"traceutil/trace.go:171","msg":"trace[296872495] linearizableReadLoop","detail":"{readStateIndex:14040; appliedIndex:14039; }","duration":"116.167156ms","start":"2026-04-16T07:27:22.97633Z","end":"2026-04-16T07:27:23.092497Z","steps":["trace[296872495] 'read index received' (duration: 59.422675ms)","trace[296872495] 'applied index is now lower than readState.Index' (duration: 56.743551ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T07:27:23.092729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.398411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T07:27:23.092749Z","caller":"traceutil/trace.go:171","msg":"trace[1115601280] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13574; }","duration":"116.452462ms","start":"2026-04-16T07:27:22.976289Z","end":"2026-04-16T07:27:23.092741Z","steps":["trace[1115601280] 'agreement among raft nodes before linearized reading' (duration: 116.271268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:26.104157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.336016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-04-16T07:27:26.104267Z","caller":"traceutil/trace.go:171","msg":"trace[1803957300] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:13609; }","duration":"123.435199ms","start":"2026-04-16T07:27:25.980793Z","end":"2026-04-16T07:27:26.104228Z","steps":["trace[1803957300] 'range keys from in-memory index tree' (duration: 123.132772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:26.104657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.437708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T07:27:26.104714Z","caller":"traceutil/trace.go:171","msg":"trace[1628130054] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:0; response_revision:13609; }","duration":"123.634283ms","start":"2026-04-16T07:27:25.981063Z","end":"2026-04-16T07:27:26.104697Z","steps":["trace[1628130054] 'range keys from in-memory index tree' (duration: 123.349687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:26.104805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.395918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T07:27:26.104884Z","caller":"traceutil/trace.go:171","msg":"trace[1492475428] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:13609; }","duration":"123.836958ms","start":"2026-04-16T07:27:25.981028Z","end":"2026-04-16T07:27:26.104865Z","steps":["trace[1492475428] 'range keys from in-memory index tree' (duration: 123.358107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:26.104942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.604763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T07:27:26.105018Z","caller":"traceutil/trace.go:171","msg":"trace[1752172090] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:13609; }","duration":"123.695775ms","start":"2026-04-16T07:27:25.981298Z","end":"2026-04-16T07:27:26.104994Z","steps":["trace[1752172090] 'range keys from in-memory index tree' (duration: 123.539361ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:27:26.137177Z","caller":"traceutil/trace.go:171","msg":"trace[1472413637] linearizableReadLoop","detail":"{readStateIndex:14079; appliedIndex:14078; }","duration":"149.867063ms","start":"2026-04-16T07:27:25.987283Z","end":"2026-04-16T07:27:26.13715Z","steps":["trace[1472413637] 'read index received' (duration: 149.686448ms)","trace[1472413637] 'applied index is now lower than readState.Index' (duration: 179.685µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:27:26.137255Z","caller":"traceutil/trace.go:171","msg":"trace[211306752] transaction","detail":"{read_only:false; response_revision:13610; number_of_response:1; }","duration":"151.738248ms","start":"2026-04-16T07:27:25.985499Z","end":"2026-04-16T07:27:26.137237Z","steps":["trace[211306752] 'process raft request' (duration: 151.503401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:26.137407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.102069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-04-16T07:27:26.137475Z","caller":"traceutil/trace.go:171","msg":"trace[810988298] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:13610; }","duration":"150.197631ms","start":"2026-04-16T07:27:25.987261Z","end":"2026-04-16T07:27:26.137458Z","steps":["trace[810988298] 'agreement among raft nodes before linearized reading' (duration: 149.993196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:32.86773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.081117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T07:27:32.86786Z","caller":"traceutil/trace.go:171","msg":"trace[1547024353] linearizableReadLoop","detail":"{readStateIndex:14180; appliedIndex:14179; }","duration":"155.683221ms","start":"2026-04-16T07:27:32.712161Z","end":"2026-04-16T07:27:32.867845Z","steps":["trace[1547024353] 'read index received' (duration: 38.316178ms)","trace[1547024353] 'applied index is now lower than readState.Index' (duration: 117.365773ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:27:32.867882Z","caller":"traceutil/trace.go:171","msg":"trace[1735533415] transaction","detail":"{read_only:false; response_revision:13707; number_of_response:1; }","duration":"204.865441ms","start":"2026-04-16T07:27:32.662981Z","end":"2026-04-16T07:27:32.867846Z","steps":["trace[1735533415] 'process raft request' (duration: 87.563369ms)","trace[1735533415] 'compare' (duration: 116.956224ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T07:27:32.867964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.795774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T07:27:32.867995Z","caller":"traceutil/trace.go:171","msg":"trace[1604595732] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:13707; }","duration":"155.850126ms","start":"2026-04-16T07:27:32.712134Z","end":"2026-04-16T07:27:32.867984Z","steps":["trace[1604595732] 'agreement among raft nodes before linearized reading' (duration: 155.794304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:32.868147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.467287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T07:27:32.868206Z","caller":"traceutil/trace.go:171","msg":"trace[374463231] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:13707; }","duration":"155.559059ms","start":"2026-04-16T07:27:32.712635Z","end":"2026-04-16T07:27:32.868194Z","steps":["trace[374463231] 'agreement among raft nodes before linearized reading' (duration: 155.442576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:32.868437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.265696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T07:27:32.868529Z","caller":"traceutil/trace.go:171","msg":"trace[1048204522] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:13707; }","duration":"156.397848ms","start":"2026-04-16T07:27:32.712109Z","end":"2026-04-16T07:27:32.868507Z","steps":["trace[1048204522] 'agreement among raft nodes before linearized reading' (duration: 156.254075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:32.868574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.246731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-04-16T07:27:32.868617Z","caller":"traceutil/trace.go:171","msg":"trace[570468220] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:13707; }","duration":"150.289092ms","start":"2026-04-16T07:27:32.718318Z","end":"2026-04-16T07:27:32.868607Z","steps":["trace[570468220] 'agreement among raft nodes before linearized reading' (duration: 150.18635ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:27:33.266285Z","caller":"traceutil/trace.go:171","msg":"trace[1441108029] transaction","detail":"{read_only:false; response_revision:13709; number_of_response:1; }","duration":"137.900166ms","start":"2026-04-16T07:27:33.128349Z","end":"2026-04-16T07:27:33.266249Z","steps":["trace[1441108029] 'process raft request' (duration: 117.385154ms)","trace[1441108029] 'compare' (duration: 20.063531ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:27:45.331794Z","caller":"traceutil/trace.go:171","msg":"trace[1413313798] transaction","detail":"{read_only:false; response_revision:13811; number_of_response:1; }","duration":"111.741489ms","start":"2026-04-16T07:27:45.220028Z","end":"2026-04-16T07:27:45.33177Z","steps":["trace[1413313798] 'process raft request' (duration: 111.580005ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:27:45.495226Z","caller":"traceutil/trace.go:171","msg":"trace[763069689] linearizableReadLoop","detail":"{readStateIndex:14290; appliedIndex:14289; }","duration":"111.303438ms","start":"2026-04-16T07:27:45.383906Z","end":"2026-04-16T07:27:45.49521Z","steps":["trace[763069689] 'read index received' (duration: 111.170585ms)","trace[763069689] 'applied index is now lower than readState.Index' (duration: 132.223µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:27:45.495396Z","caller":"traceutil/trace.go:171","msg":"trace[1049327668] transaction","detail":"{read_only:false; response_revision:13813; number_of_response:1; }","duration":"132.441855ms","start":"2026-04-16T07:27:45.362923Z","end":"2026-04-16T07:27:45.495365Z","steps":["trace[1049327668] 'process raft request' (duration: 132.156658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:45.4954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.449862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T07:27:45.495586Z","caller":"traceutil/trace.go:171","msg":"trace[827040696] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:13813; }","duration":"111.650546ms","start":"2026-04-16T07:27:45.383901Z","end":"2026-04-16T07:27:45.495551Z","steps":["trace[827040696] 'agreement among raft nodes before linearized reading' (duration: 111.40115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:45.762078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.092607ms","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-16T07:27:45.762147Z","caller":"traceutil/trace.go:171","msg":"trace[1062566599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13813; }","duration":"147.21265ms","start":"2026-04-16T07:27:45.614919Z","end":"2026-04-16T07:27:45.762131Z","steps":["trace[1062566599] 'range keys from in-memory index tree' (duration: 146.926581ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:27:45.879075Z","caller":"traceutil/trace.go:171","msg":"trace[980709253] transaction","detail":"{read_only:false; response_revision:13814; number_of_response:1; }","duration":"113.541023ms","start":"2026-04-16T07:27:45.76551Z","end":"2026-04-16T07:27:45.879051Z","steps":["trace[980709253] 'process raft request' (duration: 113.365989ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:27:46.102202Z","caller":"traceutil/trace.go:171","msg":"trace[590214149] transaction","detail":"{read_only:false; response_revision:13817; number_of_response:1; }","duration":"220.019094ms","start":"2026-04-16T07:27:45.882156Z","end":"2026-04-16T07:27:46.102175Z","steps":["trace[590214149] 'process raft request' (duration: 133.783557ms)","trace[590214149] 'compare' (duration: 86.117164ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:27:46.179997Z","caller":"traceutil/trace.go:171","msg":"trace[1627642118] linearizableReadLoop","detail":"{readStateIndex:14295; appliedIndex:14293; }","duration":"233.271931ms","start":"2026-04-16T07:27:45.94667Z","end":"2026-04-16T07:27:46.179942Z","steps":["trace[1627642118] 'read index received' (duration: 69.285271ms)","trace[1627642118] 'applied index is now lower than readState.Index' (duration: 163.98533ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:27:46.180418Z","caller":"traceutil/trace.go:171","msg":"trace[1620479010] transaction","detail":"{read_only:false; response_revision:13818; number_of_response:1; }","duration":"293.155017ms","start":"2026-04-16T07:27:45.88725Z","end":"2026-04-16T07:27:46.180405Z","steps":["trace[1620479010] 'process raft request' (duration: 292.471081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:46.180724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.035629ms","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-16T07:27:46.18077Z","caller":"traceutil/trace.go:171","msg":"trace[1776496697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13818; }","duration":"234.116492ms","start":"2026-04-16T07:27:45.946642Z","end":"2026-04-16T07:27:46.180759Z","steps":["trace[1776496697] 'agreement among raft nodes before linearized reading' (duration: 233.967118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:46.181001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.214485ms","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-16T07:27:46.18104Z","caller":"traceutil/trace.go:171","msg":"trace[27144868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13818; }","duration":"234.296947ms","start":"2026-04-16T07:27:45.946734Z","end":"2026-04-16T07:27:46.181031Z","steps":["trace[27144868] 'agreement among raft nodes before linearized reading' (duration: 234.198825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:27:46.181743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.461736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T07:27:46.181792Z","caller":"traceutil/trace.go:171","msg":"trace[805846667] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:13818; }","duration":"190.533278ms","start":"2026-04-16T07:27:45.991245Z","end":"2026-04-16T07:27:46.181779Z","steps":["trace[805846667] 'agreement among raft nodes before linearized reading' (duration: 190.465446ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:27:46.313269Z","caller":"traceutil/trace.go:171","msg":"trace[896306298] transaction","detail":"{read_only:false; response_revision:13820; number_of_response:1; }","duration":"122.481866ms","start":"2026-04-16T07:27:46.190764Z","end":"2026-04-16T07:27:46.313246Z","steps":["trace[896306298] 'process raft request' (duration: 122.397894ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:27:46.313409Z","caller":"traceutil/trace.go:171","msg":"trace[623059701] transaction","detail":"{read_only:false; response_revision:13819; number_of_response:1; }","duration":"127.250321ms","start":"2026-04-16T07:27:46.18615Z","end":"2026-04-16T07:27:46.3134Z","steps":["trace[623059701] 'process raft request' (duration: 80.263234ms)","trace[623059701] 'compare' (duration: 46.617968ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:28:54.272993Z","caller":"traceutil/trace.go:171","msg":"trace[1635671418] transaction","detail":"{read_only:false; response_revision:14317; number_of_response:1; }","duration":"131.155684ms","start":"2026-04-16T07:28:54.14181Z","end":"2026-04-16T07:28:54.272965Z","steps":["trace[1635671418] 'process raft request' (duration: 130.97881ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:28:54.39183Z","caller":"traceutil/trace.go:171","msg":"trace[1926303254] transaction","detail":"{read_only:false; response_revision:14318; number_of_response:1; }","duration":"242.72914ms","start":"2026-04-16T07:28:54.149081Z","end":"2026-04-16T07:28:54.39181Z","steps":["trace[1926303254] 'process raft request' (duration: 233.900157ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:28:54.391815Z","caller":"traceutil/trace.go:171","msg":"trace[374263763] linearizableReadLoop","detail":"{readStateIndex:14810; appliedIndex:14808; }","duration":"240.113027ms","start":"2026-04-16T07:28:54.151672Z","end":"2026-04-16T07:28:54.391785Z","steps":["trace[374263763] 'read index received' (duration: 121.164005ms)","trace[374263763] 'applied index is now lower than readState.Index' (duration: 118.947682ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T07:28:54.39194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.24193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T07:28:54.391984Z","caller":"traceutil/trace.go:171","msg":"trace[1401251051] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:14318; }","duration":"240.340692ms","start":"2026-04-16T07:28:54.151632Z","end":"2026-04-16T07:28:54.391973Z","steps":["trace[1401251051] 'agreement among raft nodes before linearized reading' (duration: 240.244679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:28:54.392332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.357088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-zg8wg\" ","response":"range_response_count:1 size:20671"} {"level":"info","ts":"2026-04-16T07:28:54.392376Z","caller":"traceutil/trace.go:171","msg":"trace[2031705108] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-zg8wg; range_end:; response_count:1; response_revision:14318; }","duration":"138.45412ms","start":"2026-04-16T07:28:54.253912Z","end":"2026-04-16T07:28:54.392366Z","steps":["trace[2031705108] 'agreement among raft nodes before linearized reading' (duration: 138.273576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:28:54.392705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.659815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T07:28:54.392869Z","caller":"traceutil/trace.go:171","msg":"trace[222829354] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14318; }","duration":"176.905701ms","start":"2026-04-16T07:28:54.215928Z","end":"2026-04-16T07:28:54.392834Z","steps":["trace[222829354] 'agreement among raft nodes before linearized reading' (duration: 176.384479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:28:54.394704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.498949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q8kzg\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-04-16T07:28:54.394805Z","caller":"traceutil/trace.go:171","msg":"trace[1332059964] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q8kzg; range_end:; response_count:1; response_revision:14318; }","duration":"193.653653ms","start":"2026-04-16T07:28:54.20113Z","end":"2026-04-16T07:28:54.394784Z","steps":["trace[1332059964] 'agreement among raft nodes before linearized reading' (duration: 190.959428ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:31:10.951865Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12567} {"level":"info","ts":"2026-04-16T07:31:11.067683Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12567,"took":"113.137383ms","hash":3312218115,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":33591296,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-16T07:31:11.067776Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3312218115,"revision":12567,"compact-revision":8625} {"level":"warn","ts":"2026-04-16T07:34:10.342435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.773688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-zg8wg\" ","response":"range_response_count:1 size:20774"} {"level":"info","ts":"2026-04-16T07:34:10.342554Z","caller":"traceutil/trace.go:171","msg":"trace[899172723] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-zg8wg; range_end:; response_count:1; response_revision:16715; }","duration":"181.934942ms","start":"2026-04-16T07:34:10.1606Z","end":"2026-04-16T07:34:10.342535Z","steps":["trace[899172723] 'range keys from in-memory index tree' (duration: 181.626395ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:34:10.868569Z","caller":"traceutil/trace.go:171","msg":"trace[26887681] linearizableReadLoop","detail":"{readStateIndex:17289; appliedIndex:17287; }","duration":"229.693556ms","start":"2026-04-16T07:34:10.638838Z","end":"2026-04-16T07:34:10.868531Z","steps":["trace[26887681] 'read index received' (duration: 229.42653ms)","trace[26887681] 'applied index is now lower than readState.Index' (duration: 265.576µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T07:34:10.868729Z","caller":"traceutil/trace.go:171","msg":"trace[997529743] transaction","detail":"{read_only:false; response_revision:16718; number_of_response:1; }","duration":"510.712545ms","start":"2026-04-16T07:34:10.358002Z","end":"2026-04-16T07:34:10.868714Z","steps":["trace[997529743] 'process raft request' (duration: 510.417157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:34:10.868913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T07:34:10.357968Z","time spent":"510.815437ms","remote":"127.0.0.1:32868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20891,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T07:34:10.86896Z","caller":"traceutil/trace.go:171","msg":"trace[69814379] transaction","detail":"{read_only:false; response_revision:16717; number_of_response:1; }","duration":"520.609332ms","start":"2026-04-16T07:34:10.3483Z","end":"2026-04-16T07:34:10.86891Z","steps":["trace[69814379] 'process raft request' (duration: 519.833163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:34:10.869183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.311623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-16T07:34:10.86928Z","caller":"traceutil/trace.go:171","msg":"trace[647475302] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:16718; }","duration":"230.484216ms","start":"2026-04-16T07:34:10.638774Z","end":"2026-04-16T07:34:10.869258Z","steps":["trace[647475302] 'agreement among raft nodes before linearized reading' (duration: 230.313352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T07:34:10.869286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T07:34:10.348278Z","time spent":"520.872478ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T07:34:10.904001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.81317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-16T07:34:10.904099Z","caller":"traceutil/trace.go:171","msg":"trace[681853192] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:16720; }","duration":"209.980594ms","start":"2026-04-16T07:34:10.694098Z","end":"2026-04-16T07:34:10.904079Z","steps":["trace[681853192] 'agreement among raft nodes before linearized reading' (duration: 209.764959ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:34:10.904348Z","caller":"traceutil/trace.go:171","msg":"trace[531238733] transaction","detail":"{read_only:false; response_revision:16719; number_of_response:1; }","duration":"127.425855ms","start":"2026-04-16T07:34:10.776867Z","end":"2026-04-16T07:34:10.904292Z","steps":["trace[531238733] 'process raft request' (duration: 126.740699ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:34:10.904575Z","caller":"traceutil/trace.go:171","msg":"trace[1251730389] transaction","detail":"{read_only:false; response_revision:16720; number_of_response:1; }","duration":"120.505629ms","start":"2026-04-16T07:34:10.783917Z","end":"2026-04-16T07:34:10.904423Z","steps":["trace[1251730389] 'process raft request' (duration: 119.883584ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T07:36:10.959798Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15519} {"level":"info","ts":"2026-04-16T07:36:11.078034Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15519,"took":"114.245606ms","hash":2826576175,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":31354880,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-16T07:36:11.078094Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2826576175,"revision":15519,"compact-revision":12567} {"level":"info","ts":"2026-04-16T07:41:10.966837Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17510} {"level":"info","ts":"2026-04-16T07:41:11.077845Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17510,"took":"107.458892ms","hash":1749567202,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":25583616,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-16T07:41:11.077921Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1749567202,"revision":17510,"compact-revision":15519} {"level":"info","ts":"2026-04-16T07:41:54.309774Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":20007,"local-member-snapshot-index":10006,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-16T07:41:54.315284Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20007} {"level":"info","ts":"2026-04-16T07:41:54.31545Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15007} {"level":"info","ts":"2026-04-16T07:46:10.973534Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19107} {"level":"info","ts":"2026-04-16T07:46:11.075564Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19107,"took":"98.744063ms","hash":580453131,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":24571904,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-16T07:46:11.07565Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":580453131,"revision":19107,"compact-revision":17510} {"level":"info","ts":"2026-04-16T07:51:10.980001Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20677} {"level":"info","ts":"2026-04-16T07:51:11.074668Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20677,"took":"90.952397ms","hash":3800623911,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":24608768,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-16T07:51:11.074751Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3800623911,"revision":20677,"compact-revision":19107} {"level":"info","ts":"2026-04-16T07:56:10.986826Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22257} {"level":"info","ts":"2026-04-16T07:56:11.081687Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22257,"took":"91.362296ms","hash":1957777240,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":24625152,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-16T07:56:11.081755Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1957777240,"revision":22257,"compact-revision":20677} {"level":"info","ts":"2026-04-16T08:01:10.992714Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23818} {"level":"info","ts":"2026-04-16T08:01:11.092393Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23818,"took":"93.308663ms","hash":2120061862,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":24997888,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-16T08:01:11.092472Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2120061862,"revision":23818,"compact-revision":22257} {"level":"info","ts":"2026-04-16T08:06:10.999821Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25445} {"level":"info","ts":"2026-04-16T08:06:11.110937Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25445,"took":"105.940154ms","hash":2379997043,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":25247744,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-16T08:06:11.111107Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2379997043,"revision":25445,"compact-revision":23818} {"level":"info","ts":"2026-04-16T08:11:11.006704Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27108} {"level":"info","ts":"2026-04-16T08:11:11.12011Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27108,"took":"109.0794ms","hash":2057467092,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":24977408,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-16T08:11:11.120196Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2057467092,"revision":27108,"compact-revision":25445} {"level":"info","ts":"2026-04-16T08:12:03.309527Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":30008,"local-member-snapshot-index":20007,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-16T08:12:03.314607Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30008} {"level":"info","ts":"2026-04-16T08:12:03.314754Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25008} {"level":"info","ts":"2026-04-16T08:15:15.101692Z","caller":"traceutil/trace.go:171","msg":"trace[1007302076] transaction","detail":"{read_only:false; response_revision:29954; number_of_response:1; }","duration":"123.484334ms","start":"2026-04-16T08:15:14.978159Z","end":"2026-04-16T08:15:15.101644Z","steps":["trace[1007302076] 'process raft request' (duration: 123.156626ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T08:16:11.01244Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28670} {"level":"info","ts":"2026-04-16T08:16:11.114725Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28670,"took":"98.247991ms","hash":328227530,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":24784896,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-16T08:16:11.114848Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":328227530,"revision":28670,"compact-revision":27108} {"level":"info","ts":"2026-04-16T08:21:11.019551Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30238} {"level":"info","ts":"2026-04-16T08:21:11.1347Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30238,"took":"110.102005ms","hash":2440102711,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":24641536,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-16T08:21:11.134772Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2440102711,"revision":30238,"compact-revision":28670} {"level":"info","ts":"2026-04-16T08:26:11.026493Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31817} {"level":"info","ts":"2026-04-16T08:26:11.138732Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31817,"took":"108.691601ms","hash":3786418087,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":23711744,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-16T08:26:11.138813Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3786418087,"revision":31817,"compact-revision":30238} {"level":"info","ts":"2026-04-16T08:29:50.670054Z","caller":"traceutil/trace.go:171","msg":"trace[59843591] transaction","detail":"{read_only:false; response_revision:34507; number_of_response:1; }","duration":"142.592842ms","start":"2026-04-16T08:29:50.52743Z","end":"2026-04-16T08:29:50.670023Z","steps":["trace[59843591] 'process raft request' (duration: 142.380527ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T08:29:50.76846Z","caller":"traceutil/trace.go:171","msg":"trace[417216520] transaction","detail":"{read_only:false; response_revision:34509; number_of_response:1; }","duration":"118.883276ms","start":"2026-04-16T08:29:50.649552Z","end":"2026-04-16T08:29:50.768436Z","steps":["trace[417216520] 'process raft request' (duration: 118.833814ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T08:29:50.768575Z","caller":"traceutil/trace.go:171","msg":"trace[1090946678] transaction","detail":"{read_only:false; response_revision:34508; number_of_response:1; }","duration":"241.072899ms","start":"2026-04-16T08:29:50.527457Z","end":"2026-04-16T08:29:50.76853Z","steps":["trace[1090946678] 'process raft request' (duration: 240.738141ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T08:29:50.789979Z","caller":"traceutil/trace.go:171","msg":"trace[1788692890] transaction","detail":"{read_only:false; response_revision:34510; number_of_response:1; }","duration":"117.187783ms","start":"2026-04-16T08:29:50.67274Z","end":"2026-04-16T08:29:50.789928Z","steps":["trace[1788692890] 'process raft request' (duration: 117.023439ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T08:29:52.045297Z","caller":"traceutil/trace.go:171","msg":"trace[479810890] transaction","detail":"{read_only:false; response_revision:34515; number_of_response:1; }","duration":"223.13723ms","start":"2026-04-16T08:29:51.82212Z","end":"2026-04-16T08:29:52.045258Z","steps":["trace[479810890] 'process raft request' (duration: 222.883684ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T08:29:52.080786Z","caller":"traceutil/trace.go:171","msg":"trace[1881491229] transaction","detail":"{read_only:false; response_revision:34516; number_of_response:1; }","duration":"227.76166ms","start":"2026-04-16T08:29:51.852981Z","end":"2026-04-16T08:29:52.080742Z","steps":["trace[1881491229] 'process raft request' (duration: 210.788144ms)","trace[1881491229] 'compare' (duration: 16.805582ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T08:29:54.864344Z","caller":"traceutil/trace.go:171","msg":"trace[2137063893] transaction","detail":"{read_only:false; response_revision:34527; number_of_response:1; }","duration":"163.44687ms","start":"2026-04-16T08:29:54.700867Z","end":"2026-04-16T08:29:54.864314Z","steps":["trace[2137063893] 'process raft request' (duration: 163.237695ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T08:29:56.256833Z","caller":"traceutil/trace.go:171","msg":"trace[2145443236] transaction","detail":"{read_only:false; response_revision:34535; number_of_response:1; }","duration":"124.430958ms","start":"2026-04-16T08:29:56.132369Z","end":"2026-04-16T08:29:56.2568Z","steps":["trace[2145443236] 'process raft request' (duration: 67.034314ms)","trace[2145443236] 'compare' (duration: 57.26259ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T08:29:57.811294Z","caller":"traceutil/trace.go:171","msg":"trace[2053452361] transaction","detail":"{read_only:false; response_revision:34543; number_of_response:1; }","duration":"170.552091ms","start":"2026-04-16T08:29:57.640675Z","end":"2026-04-16T08:29:57.811227Z","steps":["trace[2053452361] 'process raft request' (duration: 125.327569ms)","trace[2053452361] 'compare' (duration: 45.015797ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T08:29:58.526306Z","caller":"traceutil/trace.go:171","msg":"trace[1835204478] transaction","detail":"{read_only:false; response_revision:34546; number_of_response:1; }","duration":"138.900273ms","start":"2026-04-16T08:29:58.387329Z","end":"2026-04-16T08:29:58.526229Z","steps":["trace[1835204478] 'process raft request' (duration: 125.81821ms)","trace[1835204478] 'compare' (duration: 12.748975ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T08:29:59.098315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.189336ms","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-16T08:29:59.098415Z","caller":"traceutil/trace.go:171","msg":"trace[166630152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:34550; }","duration":"115.33405ms","start":"2026-04-16T08:29:58.983054Z","end":"2026-04-16T08:29:59.098388Z","steps":["trace[166630152] 'agreement among raft nodes before linearized reading' (duration: 115.043123ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T08:29:59.544125Z","caller":"traceutil/trace.go:171","msg":"trace[1991616085] transaction","detail":"{read_only:false; response_revision:34552; number_of_response:1; }","duration":"118.861194ms","start":"2026-04-16T08:29:59.425233Z","end":"2026-04-16T08:29:59.544094Z","steps":["trace[1991616085] 'process raft request' (duration: 118.620838ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T08:29:59.761134Z","caller":"traceutil/trace.go:171","msg":"trace[1047609078] transaction","detail":"{read_only:false; response_revision:34553; number_of_response:1; }","duration":"114.114071ms","start":"2026-04-16T08:29:59.64698Z","end":"2026-04-16T08:29:59.761094Z","steps":["trace[1047609078] 'process raft request' (duration: 17.13863ms)","trace[1047609078] 'compare' (duration: 96.585862ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T08:30:00.024036Z","caller":"traceutil/trace.go:171","msg":"trace[1790910501] linearizableReadLoop","detail":"{readStateIndex:35851; appliedIndex:35850; }","duration":"195.294163ms","start":"2026-04-16T08:29:59.828715Z","end":"2026-04-16T08:30:00.024009Z","steps":["trace[1790910501] 'read index received' (duration: 195.060848ms)","trace[1790910501] 'applied index is now lower than readState.Index' (duration: 232.365µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T08:30:00.024105Z","caller":"traceutil/trace.go:171","msg":"trace[94876550] transaction","detail":"{read_only:false; response_revision:34554; number_of_response:1; }","duration":"202.937197ms","start":"2026-04-16T08:29:59.821143Z","end":"2026-04-16T08:30:00.02408Z","steps":["trace[94876550] 'process raft request' (duration: 202.684351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T08:30:00.024306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.524099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-16T08:30:00.024437Z","caller":"traceutil/trace.go:171","msg":"trace[66607518] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:34554; }","duration":"195.717214ms","start":"2026-04-16T08:29:59.828691Z","end":"2026-04-16T08:30:00.024408Z","steps":["trace[66607518] 'agreement among raft nodes before linearized reading' (duration: 195.437197ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T08:30:00.334769Z","caller":"traceutil/trace.go:171","msg":"trace[823910314] transaction","detail":"{read_only:false; response_revision:34557; number_of_response:1; }","duration":"142.554521ms","start":"2026-04-16T08:30:00.192169Z","end":"2026-04-16T08:30:00.334724Z","steps":["trace[823910314] 'process raft request' (duration: 94.293846ms)","trace[823910314] 'compare' (duration: 47.964508ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T08:30:00.33491Z","caller":"traceutil/trace.go:171","msg":"trace[918694637] linearizableReadLoop","detail":"{readStateIndex:35854; appliedIndex:35853; }","duration":"128.844573ms","start":"2026-04-16T08:30:00.20602Z","end":"2026-04-16T08:30:00.334864Z","steps":["trace[918694637] 'read index received' (duration: 80.423163ms)","trace[918694637] 'applied index is now lower than readState.Index' (duration: 48.416359ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T08:30:00.335181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.60611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-16T08:30:00.335252Z","caller":"traceutil/trace.go:171","msg":"trace[1972646024] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:34557; }","duration":"144.712843ms","start":"2026-04-16T08:30:00.190515Z","end":"2026-04-16T08:30:00.335228Z","steps":["trace[1972646024] 'agreement among raft nodes before linearized reading' (duration: 144.555729ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T08:30:00.563891Z","caller":"traceutil/trace.go:171","msg":"trace[1626202458] transaction","detail":"{read_only:false; response_revision:34561; number_of_response:1; }","duration":"128.866654ms","start":"2026-04-16T08:30:00.434985Z","end":"2026-04-16T08:30:00.563851Z","steps":["trace[1626202458] 'process raft request' (duration: 128.664669ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T08:30:00.613002Z","caller":"traceutil/trace.go:171","msg":"trace[1626010274] transaction","detail":"{read_only:false; response_revision:34562; number_of_response:1; }","duration":"177.898037ms","start":"2026-04-16T08:30:00.435065Z","end":"2026-04-16T08:30:00.612963Z","steps":["trace[1626010274] 'process raft request' (duration: 177.61392ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T08:30:00.613853Z","caller":"traceutil/trace.go:171","msg":"trace[1388550755] transaction","detail":"{read_only:false; response_revision:34563; number_of_response:1; }","duration":"175.636572ms","start":"2026-04-16T08:30:00.438178Z","end":"2026-04-16T08:30:00.613814Z","steps":["trace[1388550755] 'process raft request' (duration: 174.685879ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T08:30:01.108875Z","caller":"traceutil/trace.go:171","msg":"trace[536209235] transaction","detail":"{read_only:false; response_revision:34566; number_of_response:1; }","duration":"123.510566ms","start":"2026-04-16T08:30:00.985339Z","end":"2026-04-16T08:30:01.108849Z","steps":["trace[536209235] 'process raft request' (duration: 123.320701ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T08:30:06.723522Z","caller":"traceutil/trace.go:171","msg":"trace[402915877] transaction","detail":"{read_only:false; response_revision:34609; number_of_response:1; }","duration":"115.972855ms","start":"2026-04-16T08:30:06.607518Z","end":"2026-04-16T08:30:06.723491Z","steps":["trace[402915877] 'process raft request' (duration: 115.78773ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T08:30:15.991504Z","caller":"traceutil/trace.go:171","msg":"trace[624829245] transaction","detail":"{read_only:false; response_revision:34667; number_of_response:1; }","duration":"105.457724ms","start":"2026-04-16T08:30:15.886018Z","end":"2026-04-16T08:30:15.991475Z","steps":["trace[624829245] 'process raft request' (duration: 105.249309ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T08:31:11.033887Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33380} {"level":"info","ts":"2026-04-16T08:31:11.148045Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33380,"took":"109.768337ms","hash":218364153,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":22695936,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-16T08:31:11.148133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":218364153,"revision":33380,"compact-revision":31817} {"level":"info","ts":"2026-04-16T08:36:11.040881Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34949} {"level":"info","ts":"2026-04-16T08:36:11.130352Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34949,"took":"86.668614ms","hash":3991734073,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":22130688,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-16T08:36:11.130429Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3991734073,"revision":34949,"compact-revision":33380} {"level":"info","ts":"2026-04-16T08:41:11.049373Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36583} {"level":"info","ts":"2026-04-16T08:41:11.123006Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36583,"took":"70.22762ms","hash":4018221497,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":22020096,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-16T08:41:11.123087Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4018221497,"revision":36583,"compact-revision":34949} {"level":"info","ts":"2026-04-16T08:42:29.804556Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":40009,"local-member-snapshot-index":30008,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-16T08:42:29.81012Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40009} {"level":"info","ts":"2026-04-16T08:42:29.810283Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35009} {"level":"info","ts":"2026-04-16T08:46:11.057529Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38144} {"level":"info","ts":"2026-04-16T08:46:11.131504Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38144,"took":"71.10699ms","hash":3694884524,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":21389312,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-16T08:46:11.131604Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3694884524,"revision":38144,"compact-revision":36583} {"level":"info","ts":"2026-04-16T08:51:11.065122Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39705} {"level":"info","ts":"2026-04-16T08:51:11.121634Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39705,"took":"53.772116ms","hash":2007105936,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":22339584,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-16T08:51:11.121735Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2007105936,"revision":39705,"compact-revision":38144} {"level":"info","ts":"2026-04-16T08:56:11.076078Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41279} {"level":"info","ts":"2026-04-16T08:56:11.136589Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41279,"took":"57.757492ms","hash":2268531210,"current-db-size-bytes":55664640,"current-db-size":"56 MB","current-db-size-in-use-bytes":22298624,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-16T08:56:11.136654Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2268531210,"revision":41279,"compact-revision":39705}