{"level":"warn","ts":"2026-04-08T06:16:21.134399Z","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-08T06:16:21.134479Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-08T06:16:21.134558Z","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-08T06:16:21.13457Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-04-08T06:16:21.134599Z","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-08T06:16:21.135318Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-04-08T06:16:21.135438Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-08T06:16:21.138859Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.167913ms"} {"level":"info","ts":"2026-04-08T06:16:21.145725Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-04-08T06:16:21.145819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T06:16:21.145843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-04-08T06:16:21.145854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T06:16:21.145867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-04-08T06:16:21.145906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-04-08T06:16:21.151767Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T06:16:21.155432Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T06:16:21.157626Z","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-08T06:16:21.159755Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T06:16:21.160696Z","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-08T06:16:21.160818Z","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-08T06:16:21.16084Z","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-08T06:16:21.160618Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T06:16:21.161814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-04-08T06:16:21.161946Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-04-08T06:16:21.162063Z","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-08T06:16:21.162252Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T06:16:21.162275Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T06:16:21.163447Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-04-08T06:16:21.163482Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-04-08T06:16:21.64696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T06:16:21.647039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T06:16:21.647074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-04-08T06:16:21.647099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-04-08T06:16:21.64711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-04-08T06:16:21.647129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-04-08T06:16:21.647149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-04-08T06:16:21.648821Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T06:16:21.65491Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T06:16:21.655018Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T06:16:21.655159Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T06:16:21.655192Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T06:16:21.655251Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T06:16:21.655523Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T06:16:21.655612Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T06:16:21.655636Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T06:16:21.657376Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T06:16:21.657894Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-04-08T06:16:51.195735Z","caller":"traceutil/trace.go:171","msg":"trace[1415505568] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"107.644547ms","start":"2026-04-08T06:16:51.088065Z","end":"2026-04-08T06:16:51.195709Z","steps":["trace[1415505568] 'process raft request' (duration: 102.90828ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:16:51.196559Z","caller":"traceutil/trace.go:171","msg":"trace[1644735533] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"104.703742ms","start":"2026-04-08T06:16:51.09184Z","end":"2026-04-08T06:16:51.196543Z","steps":["trace[1644735533] 'process raft request' (duration: 104.28454ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:16:51.196703Z","caller":"traceutil/trace.go:171","msg":"trace[1933544194] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"107.571634ms","start":"2026-04-08T06:16:51.089089Z","end":"2026-04-08T06:16:51.196661Z","steps":["trace[1933544194] 'process raft request' (duration: 106.939446ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:16:51.365573Z","caller":"traceutil/trace.go:171","msg":"trace[300059984] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"103.841537ms","start":"2026-04-08T06:16:51.261703Z","end":"2026-04-08T06:16:51.365544Z","steps":["trace[300059984] 'process raft request' (duration: 61.402336ms)","trace[300059984] 'compare' (duration: 42.295117ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:16:57.127216Z","caller":"traceutil/trace.go:171","msg":"trace[1608115580] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"107.173012ms","start":"2026-04-08T06:16:57.020018Z","end":"2026-04-08T06:16:57.127191Z","steps":["trace[1608115580] 'process raft request' (duration: 106.643317ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:16:57.307745Z","caller":"traceutil/trace.go:171","msg":"trace[1601354337] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"123.756541ms","start":"2026-04-08T06:16:57.183962Z","end":"2026-04-08T06:16:57.307718Z","steps":["trace[1601354337] 'process raft request' (duration: 103.041514ms)","trace[1601354337] 'compare' (duration: 20.551142ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:16:57.8212Z","caller":"traceutil/trace.go:171","msg":"trace[1289331178] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"223.094807ms","start":"2026-04-08T06:16:57.598044Z","end":"2026-04-08T06:16:57.821138Z","steps":["trace[1289331178] 'read index received' (duration: 209.28982ms)","trace[1289331178] 'applied index is now lower than readState.Index' (duration: 13.803477ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:16:57.821237Z","caller":"traceutil/trace.go:171","msg":"trace[998350178] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"401.393576ms","start":"2026-04-08T06:16:57.419799Z","end":"2026-04-08T06:16:57.821192Z","steps":["trace[998350178] 'process raft request' (duration: 387.489166ms)","trace[998350178] 'compare' (duration: 13.608942ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:16:57.821345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.296814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:16:57.82144Z","caller":"traceutil/trace.go:171","msg":"trace[413590361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"223.440328ms","start":"2026-04-08T06:16:57.597983Z","end":"2026-04-08T06:16:57.821423Z","steps":["trace[413590361] 'agreement among raft nodes before linearized reading' (duration: 223.311754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:57.821817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:57.419784Z","time spent":"401.563271ms","remote":"127.0.0.1:59838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:16:57.826266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.429559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T06:16:57.826306Z","caller":"traceutil/trace.go:171","msg":"trace[1397850171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"125.509131ms","start":"2026-04-08T06:16:57.700786Z","end":"2026-04-08T06:16:57.826295Z","steps":["trace[1397850171] 'agreement among raft nodes before linearized reading' (duration: 125.391918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:18:25.272732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.75297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:18:25.27296Z","caller":"traceutil/trace.go:171","msg":"trace[203368464] linearizableReadLoop","detail":"{readStateIndex:1892; appliedIndex:1890; }","duration":"305.516396ms","start":"2026-04-08T06:18:24.967424Z","end":"2026-04-08T06:18:25.27294Z","steps":["trace[203368464] 'read index received' (duration: 20.318464ms)","trace[203368464] 'applied index is now lower than readState.Index' (duration: 285.196622ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:18:25.27299Z","caller":"traceutil/trace.go:171","msg":"trace[830506906] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"344.799725ms","start":"2026-04-08T06:18:24.928162Z","end":"2026-04-08T06:18:25.272962Z","steps":["trace[830506906] 'process raft request' (duration: 344.702892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:18:25.273107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.66765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T06:18:25.273161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:18:24.928144Z","time spent":"344.933119ms","remote":"127.0.0.1:59914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:18:25.273143Z","caller":"traceutil/trace.go:171","msg":"trace[1537622720] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"373.254338ms","start":"2026-04-08T06:18:24.899856Z","end":"2026-04-08T06:18:25.27311Z","steps":["trace[1537622720] 'process raft request' (duration: 87.86326ms)","trace[1537622720] 'compare' (duration: 284.638407ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:18:25.273203Z","caller":"traceutil/trace.go:171","msg":"trace[2104225331] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1823; }","duration":"305.823354ms","start":"2026-04-08T06:18:24.967367Z","end":"2026-04-08T06:18:25.27319Z","steps":["trace[2104225331] 'agreement among raft nodes before linearized reading' (duration: 305.66548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:18:25.273247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:18:24.967344Z","time spent":"305.893197ms","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-04-08T06:18:25.273136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.567442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T06:18:25.273266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:18:24.899838Z","time spent":"373.373572ms","remote":"127.0.0.1:59914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:18:25.273303Z","caller":"traceutil/trace.go:171","msg":"trace[2008233451] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1823; }","duration":"155.778228ms","start":"2026-04-08T06:18:25.117508Z","end":"2026-04-08T06:18:25.273287Z","steps":["trace[2008233451] 'agreement among raft nodes before linearized reading' (duration: 155.554132ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:09.596028Z","caller":"traceutil/trace.go:171","msg":"trace[193380271] linearizableReadLoop","detail":"{readStateIndex:2199; appliedIndex:2198; }","duration":"103.42177ms","start":"2026-04-08T06:19:09.49255Z","end":"2026-04-08T06:19:09.595972Z","steps":["trace[193380271] 'read index received' (duration: 46.958096ms)","trace[193380271] 'applied index is now lower than readState.Index' (duration: 56.462364ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:09.596108Z","caller":"traceutil/trace.go:171","msg":"trace[1173960912] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"108.71124ms","start":"2026-04-08T06:19:09.487357Z","end":"2026-04-08T06:19:09.596068Z","steps":["trace[1173960912] 'process raft request' (duration: 52.134823ms)","trace[1173960912] 'compare' (duration: 56.285089ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:19:09.596319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.748949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-08T06:19:09.596367Z","caller":"traceutil/trace.go:171","msg":"trace[694765480] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2121; }","duration":"103.827121ms","start":"2026-04-08T06:19:09.492524Z","end":"2026-04-08T06:19:09.596351Z","steps":["trace[694765480] 'agreement among raft nodes before linearized reading' (duration: 103.642706ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:15.000348Z","caller":"traceutil/trace.go:171","msg":"trace[2017401025] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"117.137318ms","start":"2026-04-08T06:19:14.883186Z","end":"2026-04-08T06:19:15.000323Z","steps":["trace[2017401025] 'process raft request' (duration: 116.934522ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:15.025795Z","caller":"traceutil/trace.go:171","msg":"trace[2077253269] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"113.908916ms","start":"2026-04-08T06:19:14.911852Z","end":"2026-04-08T06:19:15.025761Z","steps":["trace[2077253269] 'process raft request' (duration: 113.751071ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:20:00.412477Z","caller":"traceutil/trace.go:171","msg":"trace[2053173411] linearizableReadLoop","detail":"{readStateIndex:2532; appliedIndex:2531; }","duration":"139.416262ms","start":"2026-04-08T06:20:00.273035Z","end":"2026-04-08T06:20:00.412451Z","steps":["trace[2053173411] 'read index received' (duration: 139.164304ms)","trace[2053173411] 'applied index is now lower than readState.Index' (duration: 251.008µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:20:00.412572Z","caller":"traceutil/trace.go:171","msg":"trace[485274404] transaction","detail":"{read_only:false; response_revision:2442; number_of_response:1; }","duration":"178.19368ms","start":"2026-04-08T06:20:00.234357Z","end":"2026-04-08T06:20:00.412551Z","steps":["trace[485274404] 'process raft request' (duration: 177.945093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:00.412741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.641378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-08T06:20:00.412777Z","caller":"traceutil/trace.go:171","msg":"trace[746911977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2442; }","duration":"139.778192ms","start":"2026-04-08T06:20:00.272988Z","end":"2026-04-08T06:20:00.412766Z","steps":["trace[746911977] 'agreement among raft nodes before linearized reading' (duration: 139.575766ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:20:32.730141Z","caller":"traceutil/trace.go:171","msg":"trace[1842422216] linearizableReadLoop","detail":"{readStateIndex:2716; appliedIndex:2715; }","duration":"132.245551ms","start":"2026-04-08T06:20:32.597869Z","end":"2026-04-08T06:20:32.730115Z","steps":["trace[1842422216] 'read index received' (duration: 91.596143ms)","trace[1842422216] 'applied index is now lower than readState.Index' (duration: 40.648268ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:20:32.730211Z","caller":"traceutil/trace.go:171","msg":"trace[466802093] transaction","detail":"{read_only:false; response_revision:2620; number_of_response:1; }","duration":"147.283893ms","start":"2026-04-08T06:20:32.582889Z","end":"2026-04-08T06:20:32.730173Z","steps":["trace[466802093] 'process raft request' (duration: 106.638595ms)","trace[466802093] 'compare' (duration: 40.480303ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:20:32.730307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.421526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:20:32.730345Z","caller":"traceutil/trace.go:171","msg":"trace[1061144786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2620; }","duration":"132.507639ms","start":"2026-04-08T06:20:32.597826Z","end":"2026-04-08T06:20:32.730334Z","steps":["trace[1061144786] 'agreement among raft nodes before linearized reading' (duration: 132.432286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:33.004161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.386626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T06:20:33.00428Z","caller":"traceutil/trace.go:171","msg":"trace[108174429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2621; }","duration":"147.51509ms","start":"2026-04-08T06:20:32.856727Z","end":"2026-04-08T06:20:33.004242Z","steps":["trace[108174429] 'range keys from in-memory index tree' (duration: 147.152669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:38.22562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.617688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-04-08T06:20:38.225798Z","caller":"traceutil/trace.go:171","msg":"trace[1681422621] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2648; }","duration":"112.840174ms","start":"2026-04-08T06:20:38.112931Z","end":"2026-04-08T06:20:38.225771Z","steps":["trace[1681422621] 'range keys from in-memory index tree' (duration: 112.414792ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:23:10.406248Z","caller":"traceutil/trace.go:171","msg":"trace[1134769429] transaction","detail":"{read_only:false; response_revision:3643; number_of_response:1; }","duration":"118.908192ms","start":"2026-04-08T06:23:10.287306Z","end":"2026-04-08T06:23:10.406214Z","steps":["trace[1134769429] 'process raft request' (duration: 118.713806ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:24:25.827586Z","caller":"traceutil/trace.go:171","msg":"trace[1979914111] linearizableReadLoop","detail":"{readStateIndex:4919; appliedIndex:4918; }","duration":"135.074939ms","start":"2026-04-08T06:24:25.692489Z","end":"2026-04-08T06:24:25.827564Z","steps":["trace[1979914111] 'read index received' (duration: 134.852362ms)","trace[1979914111] 'applied index is now lower than readState.Index' (duration: 221.637µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:24:25.827689Z","caller":"traceutil/trace.go:171","msg":"trace[1593162227] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"151.187295ms","start":"2026-04-08T06:24:25.676494Z","end":"2026-04-08T06:24:25.827681Z","steps":["trace[1593162227] 'process raft request' (duration: 150.961179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:24:25.828029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.532591ms","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-08T06:24:25.828058Z","caller":"traceutil/trace.go:171","msg":"trace[1740331139] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4743; }","duration":"135.601913ms","start":"2026-04-08T06:24:25.692449Z","end":"2026-04-08T06:24:25.828051Z","steps":["trace[1740331139] 'agreement among raft nodes before linearized reading' (duration: 135.48386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:24:33.4579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.766126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:24:33.458046Z","caller":"traceutil/trace.go:171","msg":"trace[1016313481] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"135.305316ms","start":"2026-04-08T06:24:33.322715Z","end":"2026-04-08T06:24:33.458021Z","steps":["trace[1016313481] 'process raft request' (duration: 34.313503ms)","trace[1016313481] 'compare' (duration: 100.56914ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:24:44.772224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.832777ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-08T06:24:44.772384Z","caller":"traceutil/trace.go:171","msg":"trace[368029871] linearizableReadLoop","detail":"{readStateIndex:5309; appliedIndex:5308; }","duration":"114.911401ms","start":"2026-04-08T06:24:44.657453Z","end":"2026-04-08T06:24:44.772364Z","steps":["trace[368029871] 'read index received' (duration: 5.842527ms)","trace[368029871] 'applied index is now lower than readState.Index' (duration: 109.067134ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:24:44.772594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.140598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-pushgateway-7c6f84b96c-njzx9\" ","response":"range_response_count:1 size:4040"} {"level":"info","ts":"2026-04-08T06:24:44.772633Z","caller":"traceutil/trace.go:171","msg":"trace[1865388813] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-pushgateway-7c6f84b96c-njzx9; range_end:; response_count:1; response_revision:5123; }","duration":"115.243391ms","start":"2026-04-08T06:24:44.65738Z","end":"2026-04-08T06:24:44.772623Z","steps":["trace[1865388813] 'agreement among raft nodes before linearized reading' (duration: 115.069425ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:44.987105Z","caller":"traceutil/trace.go:171","msg":"trace[293224812] transaction","detail":"{read_only:false; response_revision:5731; number_of_response:1; }","duration":"124.624727ms","start":"2026-04-08T06:25:44.862158Z","end":"2026-04-08T06:25:44.986783Z","steps":["trace[293224812] 'process raft request' (duration: 124.472213ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:26:21.689575Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2878} {"level":"info","ts":"2026-04-08T06:26:21.755331Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2878,"took":"64.076209ms","hash":1790946641,"current-db-size-bytes":58499072,"current-db-size":"58 MB","current-db-size-in-use-bytes":33583104,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-08T06:26:21.755393Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1790946641,"revision":2878,"compact-revision":-1} {"level":"info","ts":"2026-04-08T06:30:25.249303Z","caller":"traceutil/trace.go:171","msg":"trace[1973002750] linearizableReadLoop","detail":"{readStateIndex:8119; appliedIndex:8118; }","duration":"188.550069ms","start":"2026-04-08T06:30:25.060724Z","end":"2026-04-08T06:30:25.249274Z","steps":["trace[1973002750] 'read index received' (duration: 188.275841ms)","trace[1973002750] 'applied index is now lower than readState.Index' (duration: 272.838µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:30:25.249339Z","caller":"traceutil/trace.go:171","msg":"trace[1270532271] transaction","detail":"{read_only:false; response_revision:7848; number_of_response:1; }","duration":"203.432909ms","start":"2026-04-08T06:30:25.045876Z","end":"2026-04-08T06:30:25.249309Z","steps":["trace[1270532271] 'process raft request' (duration: 203.134071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:30:25.249536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.781056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T06:30:25.24958Z","caller":"traceutil/trace.go:171","msg":"trace[1765371457] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:7848; }","duration":"188.898329ms","start":"2026-04-08T06:30:25.060669Z","end":"2026-04-08T06:30:25.249568Z","steps":["trace[1765371457] 'agreement among raft nodes before linearized reading' (duration: 188.786296ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:30:36.338281Z","caller":"traceutil/trace.go:171","msg":"trace[670683226] transaction","detail":"{read_only:false; response_revision:7908; number_of_response:1; }","duration":"123.730654ms","start":"2026-04-08T06:30:36.214511Z","end":"2026-04-08T06:30:36.338242Z","steps":["trace[670683226] 'process raft request' (duration: 123.537919ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:30:37.492942Z","caller":"traceutil/trace.go:171","msg":"trace[615303407] transaction","detail":"{read_only:false; response_revision:7912; number_of_response:1; }","duration":"112.162188ms","start":"2026-04-08T06:30:37.380757Z","end":"2026-04-08T06:30:37.492919Z","steps":["trace[615303407] 'process raft request' (duration: 111.979123ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:30:37.49293Z","caller":"traceutil/trace.go:171","msg":"trace[7162267] linearizableReadLoop","detail":"{readStateIndex:8186; appliedIndex:8185; }","duration":"106.724716ms","start":"2026-04-08T06:30:37.386176Z","end":"2026-04-08T06:30:37.492901Z","steps":["trace[7162267] 'read index received' (duration: 106.453638ms)","trace[7162267] 'applied index is now lower than readState.Index' (duration: 269.758µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:30:37.493161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.959962ms","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":"warn","ts":"2026-04-08T06:30:37.493183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.321427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-08T06:30:37.493219Z","caller":"traceutil/trace.go:171","msg":"trace[1163170970] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7912; }","duration":"107.066035ms","start":"2026-04-08T06:30:37.38614Z","end":"2026-04-08T06:30:37.493206Z","steps":["trace[1163170970] 'agreement among raft nodes before linearized reading' (duration: 106.87749ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:30:37.493229Z","caller":"traceutil/trace.go:171","msg":"trace[1877574002] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7912; }","duration":"105.394908ms","start":"2026-04-08T06:30:37.387817Z","end":"2026-04-08T06:30:37.493212Z","steps":["trace[1877574002] 'agreement among raft nodes before linearized reading' (duration: 105.243324ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:30:37.808003Z","caller":"traceutil/trace.go:171","msg":"trace[1522431991] transaction","detail":"{read_only:false; response_revision:7913; number_of_response:1; }","duration":"310.328019ms","start":"2026-04-08T06:30:37.497651Z","end":"2026-04-08T06:30:37.807979Z","steps":["trace[1522431991] 'process raft request' (duration: 251.183284ms)","trace[1522431991] 'compare' (duration: 58.871638ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:30:37.808279Z","caller":"traceutil/trace.go:171","msg":"trace[717459894] linearizableReadLoop","detail":"{readStateIndex:8188; appliedIndex:8186; }","duration":"210.357574ms","start":"2026-04-08T06:30:37.597882Z","end":"2026-04-08T06:30:37.808239Z","steps":["trace[717459894] 'read index received' (duration: 150.972952ms)","trace[717459894] 'applied index is now lower than readState.Index' (duration: 59.383122ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:30:37.808576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:30:37.497635Z","time spent":"310.70873ms","remote":"127.0.0.1:59914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:30:37.808733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.822517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:30:37.808783Z","caller":"traceutil/trace.go:171","msg":"trace[1091311422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7914; }","duration":"210.93497ms","start":"2026-04-08T06:30:37.597839Z","end":"2026-04-08T06:30:37.808774Z","steps":["trace[1091311422] 'agreement among raft nodes before linearized reading' (duration: 210.824297ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:30:37.808635Z","caller":"traceutil/trace.go:171","msg":"trace[1881584098] transaction","detail":"{read_only:false; response_revision:7914; number_of_response:1; }","duration":"310.621118ms","start":"2026-04-08T06:30:37.497995Z","end":"2026-04-08T06:30:37.808616Z","steps":["trace[1881584098] 'process raft request' (duration: 309.936229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:30:37.809282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:30:37.497975Z","time spent":"311.258366ms","remote":"127.0.0.1:59914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:31:03.707457Z","caller":"traceutil/trace.go:171","msg":"trace[1858176253] transaction","detail":"{read_only:false; response_revision:8103; number_of_response:1; }","duration":"246.693017ms","start":"2026-04-08T06:31:03.460745Z","end":"2026-04-08T06:31:03.707438Z","steps":["trace[1858176253] 'process raft request' (duration: 246.563054ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:31:03.712711Z","caller":"traceutil/trace.go:171","msg":"trace[1311373656] linearizableReadLoop","detail":"{readStateIndex:8384; appliedIndex:8382; }","duration":"112.334253ms","start":"2026-04-08T06:31:03.600361Z","end":"2026-04-08T06:31:03.712695Z","steps":["trace[1311373656] 'read index received' (duration: 107.021524ms)","trace[1311373656] 'applied index is now lower than readState.Index' (duration: 5.312009ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:31:03.712736Z","caller":"traceutil/trace.go:171","msg":"trace[1322431112] transaction","detail":"{read_only:false; response_revision:8104; number_of_response:1; }","duration":"132.025948ms","start":"2026-04-08T06:31:03.580696Z","end":"2026-04-08T06:31:03.712722Z","steps":["trace[1322431112] 'process raft request' (duration: 131.922215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:31:03.712797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.429456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:31:03.712818Z","caller":"traceutil/trace.go:171","msg":"trace[1914685022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8104; }","duration":"112.492048ms","start":"2026-04-08T06:31:03.60032Z","end":"2026-04-08T06:31:03.712812Z","steps":["trace[1914685022] 'agreement among raft nodes before linearized reading' (duration: 112.441296ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:31:03.896736Z","caller":"traceutil/trace.go:171","msg":"trace[802308675] transaction","detail":"{read_only:false; response_revision:8105; number_of_response:1; }","duration":"126.034679ms","start":"2026-04-08T06:31:03.770678Z","end":"2026-04-08T06:31:03.896713Z","steps":["trace[802308675] 'process raft request' (duration: 125.874524ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:31:16.595499Z","caller":"traceutil/trace.go:171","msg":"trace[1749294649] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8216; }","duration":"109.74131ms","start":"2026-04-08T06:31:16.485733Z","end":"2026-04-08T06:31:16.595474Z","steps":["trace[1749294649] 'process raft request' (duration: 105.688395ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:31:16.613888Z","caller":"traceutil/trace.go:171","msg":"trace[2077998331] transaction","detail":"{read_only:false; response_revision:8217; number_of_response:1; }","duration":"127.069958ms","start":"2026-04-08T06:31:16.486776Z","end":"2026-04-08T06:31:16.613846Z","steps":["trace[2077998331] 'process raft request' (duration: 126.827541ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:31:18.470167Z","caller":"traceutil/trace.go:171","msg":"trace[353731385] transaction","detail":"{read_only:false; response_revision:8229; number_of_response:1; }","duration":"272.595836ms","start":"2026-04-08T06:31:18.197547Z","end":"2026-04-08T06:31:18.470143Z","steps":["trace[353731385] 'process raft request' (duration: 272.430002ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:31:18.483494Z","caller":"traceutil/trace.go:171","msg":"trace[227586946] transaction","detail":"{read_only:false; response_revision:8230; number_of_response:1; }","duration":"170.322806ms","start":"2026-04-08T06:31:18.313149Z","end":"2026-04-08T06:31:18.483472Z","steps":["trace[227586946] 'process raft request' (duration: 170.197183ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:31:19.093285Z","caller":"traceutil/trace.go:171","msg":"trace[958918279] transaction","detail":"{read_only:false; response_revision:8235; number_of_response:1; }","duration":"155.101039ms","start":"2026-04-08T06:31:18.938146Z","end":"2026-04-08T06:31:19.093247Z","steps":["trace[958918279] 'process raft request' (duration: 154.935733ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:31:21.696615Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6026} {"level":"info","ts":"2026-04-08T06:31:21.804798Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6026,"took":"106.422367ms","hash":4472704,"current-db-size-bytes":58499072,"current-db-size":"58 MB","current-db-size-in-use-bytes":22675456,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-08T06:31:21.804888Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4472704,"revision":6026,"compact-revision":2878} {"level":"info","ts":"2026-04-08T06:32:24.941728Z","caller":"traceutil/trace.go:171","msg":"trace[370097170] transaction","detail":"{read_only:false; response_revision:8863; number_of_response:1; }","duration":"143.01635ms","start":"2026-04-08T06:32:24.798687Z","end":"2026-04-08T06:32:24.941703Z","steps":["trace[370097170] 'process raft request' (duration: 142.937668ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:32:24.941906Z","caller":"traceutil/trace.go:171","msg":"trace[970284049] transaction","detail":"{read_only:false; response_revision:8862; number_of_response:1; }","duration":"215.985766ms","start":"2026-04-08T06:32:24.725893Z","end":"2026-04-08T06:32:24.941879Z","steps":["trace[970284049] 'process raft request' (duration: 182.657847ms)","trace[970284049] 'compare' (duration: 32.910357ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:32:24.942058Z","caller":"traceutil/trace.go:171","msg":"trace[22256599] linearizableReadLoop","detail":"{readStateIndex:9167; appliedIndex:9165; }","duration":"210.555054ms","start":"2026-04-08T06:32:24.731491Z","end":"2026-04-08T06:32:24.942046Z","steps":["trace[22256599] 'read index received' (duration: 65.688492ms)","trace[22256599] 'applied index is now lower than readState.Index' (duration: 144.864972ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:32:24.942157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.655196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:32:24.942197Z","caller":"traceutil/trace.go:171","msg":"trace[1155673825] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8863; }","duration":"210.713778ms","start":"2026-04-08T06:32:24.731473Z","end":"2026-04-08T06:32:24.942186Z","steps":["trace[1155673825] 'agreement among raft nodes before linearized reading' (duration: 210.630105ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:32:37.638037Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000244d.wal"} {"level":"info","ts":"2026-04-08T06:34:00.114738Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-08T06:34:00.121382Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-08T06:34:00.121499Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-08T06:36:21.704709Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8260} {"level":"info","ts":"2026-04-08T06:36:21.783109Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8260,"took":"76.150412ms","hash":665741552,"current-db-size-bytes":58499072,"current-db-size":"58 MB","current-db-size-in-use-bytes":24735744,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-08T06:36:21.783175Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":665741552,"revision":8260,"compact-revision":6026} {"level":"info","ts":"2026-04-08T06:41:21.710923Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10585} {"level":"info","ts":"2026-04-08T06:41:21.784664Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10585,"took":"71.52684ms","hash":241263275,"current-db-size-bytes":58499072,"current-db-size":"58 MB","current-db-size-in-use-bytes":19591168,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-08T06:41:21.784733Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":241263275,"revision":10585,"compact-revision":8260}