{"level":"warn","ts":"2026-03-09T01:39:21.265374Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-09T01:39:21.265463Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.65: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.65:2380","--initial-cluster=instance=https://199.204.45.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.65: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-03-09T01:39:21.265525Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-09T01:39:21.265533Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-03-09T01:39:21.265553Z","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-03-09T01:39:21.266261Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"]} {"level":"info","ts":"2026-03-09T01:39:21.266349Z","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.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.65: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-03-09T01:39:21.270416Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.823781ms"} {"level":"info","ts":"2026-03-09T01:39:21.27853Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf8c3a138c9a77a2","cluster-id":"c278183e1f634dff"} {"level":"info","ts":"2026-03-09T01:39:21.278604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=()"} {"level":"info","ts":"2026-03-09T01:39:21.278624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 0"} {"level":"info","ts":"2026-03-09T01:39:21.278637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf8c3a138c9a77a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-09T01:39:21.278646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 1"} {"level":"info","ts":"2026-03-09T01:39:21.278683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"warn","ts":"2026-03-09T01:39:21.288959Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-09T01:39:21.29062Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-09T01:39:21.291895Z","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-03-09T01:39:21.29429Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf8c3a138c9a77a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-09T01:39:21.294553Z","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-03-09T01:39:21.294692Z","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-03-09T01:39:21.294724Z","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-03-09T01:39:21.294525Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf8c3a138c9a77a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-09T01:39:21.296759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"info","ts":"2026-03-09T01:39:21.297442Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","added-peer-id":"cf8c3a138c9a77a2","added-peer-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-03-09T01:39:21.29953Z","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-03-09T01:39:21.299783Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-03-09T01:39:21.299846Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-03-09T01:39:21.29995Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf8c3a138c9a77a2","initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-09T01:39:21.30001Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-09T01:39:22.279538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 is starting a new election at term 1"} {"level":"info","ts":"2026-03-09T01:39:22.279621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-09T01:39:22.279668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgPreVoteResp from cf8c3a138c9a77a2 at term 1"} {"level":"info","ts":"2026-03-09T01:39:22.279699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became candidate at term 2"} {"level":"info","ts":"2026-03-09T01:39:22.279715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgVoteResp from cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-03-09T01:39:22.279759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became leader at term 2"} {"level":"info","ts":"2026-03-09T01:39:22.279779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf8c3a138c9a77a2 elected leader cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-03-09T01:39:22.280882Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:39:22.281765Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf8c3a138c9a77a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.65:2379]}","request-path":"/0/members/cf8c3a138c9a77a2/attributes","cluster-id":"c278183e1f634dff","publish-timeout":"7s"} {"level":"info","ts":"2026-03-09T01:39:22.281777Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T01:39:22.281826Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T01:39:22.282349Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-09T01:39:22.28241Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-09T01:39:22.282437Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:39:22.282557Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:39:22.282602Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:39:22.285465Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-09T01:39:22.285583Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.65:2379"} {"level":"warn","ts":"2026-03-09T01:39:30.962643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.339782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-09T01:39:30.962819Z","caller":"traceutil/trace.go:171","msg":"trace[1240347656] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:303; }","duration":"116.573457ms","start":"2026-03-09T01:39:30.846217Z","end":"2026-03-09T01:39:30.96279Z","steps":["trace[1240347656] 'range keys from in-memory index tree' (duration: 116.22645ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:39:56.734661Z","caller":"traceutil/trace.go:171","msg":"trace[220145080] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"130.748001ms","start":"2026-03-09T01:39:56.603889Z","end":"2026-03-09T01:39:56.734637Z","steps":["trace[220145080] 'process raft request' (duration: 130.561307ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:40:02.040059Z","caller":"traceutil/trace.go:171","msg":"trace[1662660266] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"179.369647ms","start":"2026-03-09T01:40:01.860653Z","end":"2026-03-09T01:40:02.040022Z","steps":["trace[1662660266] 'process raft request' (duration: 166.52577ms)","trace[1662660266] 'compare' (duration: 12.440629ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:40:02.040369Z","caller":"traceutil/trace.go:171","msg":"trace[1039846700] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"179.411909ms","start":"2026-03-09T01:40:01.860915Z","end":"2026-03-09T01:40:02.040327Z","steps":["trace[1039846700] 'process raft request' (duration: 178.946539ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:40:02.278554Z","caller":"traceutil/trace.go:171","msg":"trace[1643298651] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"195.792222ms","start":"2026-03-09T01:40:02.082732Z","end":"2026-03-09T01:40:02.278524Z","steps":["trace[1643298651] 'process raft request' (duration: 159.118695ms)","trace[1643298651] 'compare' (duration: 36.480923ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:40:02.326466Z","caller":"traceutil/trace.go:171","msg":"trace[445369407] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"243.496007ms","start":"2026-03-09T01:40:02.082941Z","end":"2026-03-09T01:40:02.326437Z","steps":["trace[445369407] 'process raft request' (duration: 243.187811ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:40:02.326563Z","caller":"traceutil/trace.go:171","msg":"trace[1655857536] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"238.35345ms","start":"2026-03-09T01:40:02.088155Z","end":"2026-03-09T01:40:02.326509Z","steps":["trace[1655857536] 'process raft request' (duration: 238.123915ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:40:02.32662Z","caller":"traceutil/trace.go:171","msg":"trace[1040936223] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:548; }","duration":"121.885136ms","start":"2026-03-09T01:40:02.204713Z","end":"2026-03-09T01:40:02.326598Z","steps":["trace[1040936223] 'read index received' (duration: 37.137535ms)","trace[1040936223] 'applied index is now lower than readState.Index' (duration: 84.746681ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:40:02.326701Z","caller":"traceutil/trace.go:171","msg":"trace[935527436] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"227.088864ms","start":"2026-03-09T01:40:02.099598Z","end":"2026-03-09T01:40:02.326687Z","steps":["trace[935527436] 'process raft request' (duration: 226.820399ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:40:02.326736Z","caller":"traceutil/trace.go:171","msg":"trace[1728606601] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"224.564891ms","start":"2026-03-09T01:40:02.10216Z","end":"2026-03-09T01:40:02.326725Z","steps":["trace[1728606601] 'process raft request' (duration: 224.396038ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:40:02.326797Z","caller":"traceutil/trace.go:171","msg":"trace[1771276987] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"226.285567ms","start":"2026-03-09T01:40:02.100425Z","end":"2026-03-09T01:40:02.326711Z","steps":["trace[1771276987] 'process raft request' (duration: 226.059312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:40:02.326914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.180642ms","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-03-09T01:40:02.326991Z","caller":"traceutil/trace.go:171","msg":"trace[775528336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:536; }","duration":"122.306435ms","start":"2026-03-09T01:40:02.204663Z","end":"2026-03-09T01:40:02.32697Z","steps":["trace[775528336] 'agreement among raft nodes before linearized reading' (duration: 122.07506ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:40:02.838295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.699909ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-09T01:40:02.838679Z","caller":"traceutil/trace.go:171","msg":"trace[1109929099] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"547.813085ms","start":"2026-03-09T01:40:02.290817Z","end":"2026-03-09T01:40:02.83863Z","steps":["trace[1109929099] 'process raft request' (duration: 547.613891ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:40:02.838907Z","caller":"traceutil/trace.go:171","msg":"trace[1069660595] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"505.264546ms","start":"2026-03-09T01:40:02.333614Z","end":"2026-03-09T01:40:02.838878Z","steps":["trace[1069660595] 'process raft request' (duration: 504.9372ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:40:02.839062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:02.333598Z","time spent":"505.395839ms","remote":"127.0.0.1:52724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-09T01:40:02.839162Z","caller":"traceutil/trace.go:171","msg":"trace[487516574] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"494.962101ms","start":"2026-03-09T01:40:02.34416Z","end":"2026-03-09T01:40:02.839122Z","steps":["trace[487516574] 'process raft request' (duration: 494.864939ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:40:02.839299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:02.290789Z","time spent":"548.00613ms","remote":"127.0.0.1:52318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9932,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T01:40:02.839366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:02.344135Z","time spent":"495.137794ms","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1451,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-09T01:40:02.839355Z","caller":"traceutil/trace.go:171","msg":"trace[201283829] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"495.055203ms","start":"2026-03-09T01:40:02.344262Z","end":"2026-03-09T01:40:02.839318Z","steps":["trace[201283829] 'process raft request' (duration: 494.812938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:40:02.839417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.999464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lpwtw\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-09T01:40:02.839499Z","caller":"traceutil/trace.go:171","msg":"trace[1459886692] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lpwtw; range_end:; response_count:1; response_revision:542; }","duration":"520.133677ms","start":"2026-03-09T01:40:02.319346Z","end":"2026-03-09T01:40:02.83948Z","steps":["trace[1459886692] 'agreement among raft nodes before linearized reading' (duration: 519.595136ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:40:02.839504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:02.344256Z","time spent":"495.178626ms","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1450,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T01:40:02.839544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:02.31933Z","time spent":"520.205298ms","remote":"127.0.0.1:52446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-lpwtw\" "} {"level":"info","ts":"2026-03-09T01:40:02.839545Z","caller":"traceutil/trace.go:171","msg":"trace[584097134] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"495.076643ms","start":"2026-03-09T01:40:02.344432Z","end":"2026-03-09T01:40:02.839509Z","steps":["trace[584097134] 'process raft request' (duration: 494.855809ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:40:02.839643Z","caller":"traceutil/trace.go:171","msg":"trace[264015703] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"494.87098ms","start":"2026-03-09T01:40:02.344759Z","end":"2026-03-09T01:40:02.83963Z","steps":["trace[264015703] 'process raft request' (duration: 494.765877ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:40:02.839653Z","caller":"traceutil/trace.go:171","msg":"trace[728287580] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"494.925352ms","start":"2026-03-09T01:40:02.344703Z","end":"2026-03-09T01:40:02.839629Z","steps":["trace[728287580] 'process raft request' (duration: 494.791288ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:40:02.838567Z","caller":"traceutil/trace.go:171","msg":"trace[1548015257] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"552.857111ms","start":"2026-03-09T01:40:02.285671Z","end":"2026-03-09T01:40:02.838528Z","steps":["trace[1548015257] 'process raft request' (duration: 266.286874ms)","trace[1548015257] 'compare' (duration: 285.488375ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:40:02.839668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:02.344425Z","time spent":"495.187116ms","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1174,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T01:40:02.839698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:02.344744Z","time spent":"494.933052ms","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1119,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-09T01:40:02.839669Z","caller":"traceutil/trace.go:171","msg":"trace[584324313] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"494.858549ms","start":"2026-03-09T01:40:02.344697Z","end":"2026-03-09T01:40:02.839555Z","steps":["trace[584324313] 'process raft request' (duration: 494.758897ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:40:02.83972Z","caller":"traceutil/trace.go:171","msg":"trace[1046675688] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"494.905971ms","start":"2026-03-09T01:40:02.344745Z","end":"2026-03-09T01:40:02.839651Z","steps":["trace[1046675688] 'process raft request' (duration: 494.812438ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:40:02.839794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:02.285646Z","time spent":"554.077447ms","remote":"127.0.0.1:52724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-09T01:40:02.839869Z","caller":"traceutil/trace.go:171","msg":"trace[1933962486] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"492.746435ms","start":"2026-03-09T01:40:02.347105Z","end":"2026-03-09T01:40:02.839851Z","steps":["trace[1933962486] 'process raft request' (duration: 492.48605ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:40:02.839875Z","caller":"traceutil/trace.go:171","msg":"trace[972424594] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"492.211545ms","start":"2026-03-09T01:40:02.34765Z","end":"2026-03-09T01:40:02.839862Z","steps":["trace[972424594] 'process raft request' (duration: 492.017491ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:40:02.839884Z","caller":"traceutil/trace.go:171","msg":"trace[1967032836] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"492.101452ms","start":"2026-03-09T01:40:02.347771Z","end":"2026-03-09T01:40:02.839872Z","steps":["trace[1967032836] 'process raft request' (duration: 491.927238ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:40:02.839947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:02.347048Z","time spent":"492.862168ms","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1332,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T01:40:02.839965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:02.347636Z","time spent":"492.293226ms","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1422,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T01:40:02.839957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:02.347757Z","time spent":"492.175844ms","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1584,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-09T01:40:02.839769Z","caller":"traceutil/trace.go:171","msg":"trace[1360290284] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"492.248875ms","start":"2026-03-09T01:40:02.347499Z","end":"2026-03-09T01:40:02.839748Z","steps":["trace[1360290284] 'process raft request' (duration: 492.128143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:40:02.83996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:02.344733Z","time spent":"495.132226ms","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1048,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-09T01:40:02.839976Z","caller":"traceutil/trace.go:171","msg":"trace[221217756] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"505.625435ms","start":"2026-03-09T01:40:02.334166Z","end":"2026-03-09T01:40:02.839791Z","steps":["trace[221217756] 'process raft request' (duration: 504.689085ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:40:02.839738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:02.344689Z","time spent":"495.007883ms","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1330,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T01:40:02.840056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:02.347477Z","time spent":"492.544711ms","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1416,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T01:40:02.839974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:02.344687Z","time spent":"495.121975ms","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-09T01:40:02.838835Z","caller":"traceutil/trace.go:171","msg":"trace[293296778] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:554; }","duration":"512.1212ms","start":"2026-03-09T01:40:02.326691Z","end":"2026-03-09T01:40:02.838813Z","steps":["trace[293296778] 'read index received' (duration: 225.485441ms)","trace[293296778] 'applied index is now lower than readState.Index' (duration: 286.633729ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:40:02.840291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:02.334153Z","time spent":"506.004993ms","remote":"127.0.0.1:52506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T01:40:02.839808Z","caller":"traceutil/trace.go:171","msg":"trace[1708597620] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"491.633753ms","start":"2026-03-09T01:40:02.348135Z","end":"2026-03-09T01:40:02.839768Z","steps":["trace[1708597620] 'process raft request' (duration: 491.592332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:40:02.840534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:02.348076Z","time spent":"492.413198ms","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1209,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T01:41:25.601467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.005975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:41:25.60161Z","caller":"traceutil/trace.go:171","msg":"trace[1528929946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1597; }","duration":"111.240221ms","start":"2026-03-09T01:41:25.49034Z","end":"2026-03-09T01:41:25.60158Z","steps":["trace[1528929946] 'range keys from in-memory index tree' (duration: 110.845752ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:42:10.665943Z","caller":"traceutil/trace.go:171","msg":"trace[1605310207] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"125.05254ms","start":"2026-03-09T01:42:10.540844Z","end":"2026-03-09T01:42:10.665896Z","steps":["trace[1605310207] 'process raft request' (duration: 124.58606ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:43:05.075996Z","caller":"traceutil/trace.go:171","msg":"trace[549501768] linearizableReadLoop","detail":"{readStateIndex:2400; appliedIndex:2399; }","duration":"198.965135ms","start":"2026-03-09T01:43:04.877004Z","end":"2026-03-09T01:43:05.075969Z","steps":["trace[549501768] 'read index received' (duration: 198.874213ms)","trace[549501768] 'applied index is now lower than readState.Index' (duration: 89.122µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:43:05.076123Z","caller":"traceutil/trace.go:171","msg":"trace[794560107] transaction","detail":"{read_only:false; response_revision:2312; number_of_response:1; }","duration":"206.166287ms","start":"2026-03-09T01:43:04.869886Z","end":"2026-03-09T01:43:05.076052Z","steps":["trace[794560107] 'process raft request' (duration: 205.905012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:43:05.076186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.15551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-09T01:43:05.076414Z","caller":"traceutil/trace.go:171","msg":"trace[624993849] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2312; }","duration":"199.420235ms","start":"2026-03-09T01:43:04.87695Z","end":"2026-03-09T01:43:05.076371Z","steps":["trace[624993849] 'agreement among raft nodes before linearized reading' (duration: 199.163859ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:43:05.07662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.613201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-09T01:43:05.076688Z","caller":"traceutil/trace.go:171","msg":"trace[577177293] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2313; }","duration":"105.732264ms","start":"2026-03-09T01:43:04.970939Z","end":"2026-03-09T01:43:05.076671Z","steps":["trace[577177293] 'agreement among raft nodes before linearized reading' (duration: 105.519269ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:43:05.076794Z","caller":"traceutil/trace.go:171","msg":"trace[850970699] transaction","detail":"{read_only:false; response_revision:2313; number_of_response:1; }","duration":"101.972015ms","start":"2026-03-09T01:43:04.974807Z","end":"2026-03-09T01:43:05.076778Z","steps":["trace[850970699] 'process raft request' (duration: 101.575327ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:43:39.564229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.690812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T01:43:39.564465Z","caller":"traceutil/trace.go:171","msg":"trace[805318385] transaction","detail":"{read_only:false; response_revision:2505; number_of_response:1; }","duration":"287.26893ms","start":"2026-03-09T01:43:39.277174Z","end":"2026-03-09T01:43:39.564443Z","steps":["trace[805318385] 'process raft request' (duration: 115.253281ms)","trace[805318385] 'compare' (duration: 171.546559ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:43:39.564803Z","caller":"traceutil/trace.go:171","msg":"trace[305094370] transaction","detail":"{read_only:false; response_revision:2506; number_of_response:1; }","duration":"285.033294ms","start":"2026-03-09T01:43:39.279748Z","end":"2026-03-09T01:43:39.564781Z","steps":["trace[305094370] 'process raft request' (duration: 284.596974ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:47:30.326405Z","caller":"traceutil/trace.go:171","msg":"trace[783600272] transaction","detail":"{read_only:false; response_revision:4330; number_of_response:1; }","duration":"129.342354ms","start":"2026-03-09T01:47:30.197021Z","end":"2026-03-09T01:47:30.326363Z","steps":["trace[783600272] 'process raft request' (duration: 129.109349ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:47:51.107467Z","caller":"traceutil/trace.go:171","msg":"trace[1085226858] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"138.62604ms","start":"2026-03-09T01:47:50.96881Z","end":"2026-03-09T01:47:51.107436Z","steps":["trace[1085226858] 'process raft request' (duration: 137.774373ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:47:51.107694Z","caller":"traceutil/trace.go:171","msg":"trace[1521493374] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"138.877435ms","start":"2026-03-09T01:47:50.968804Z","end":"2026-03-09T01:47:51.107682Z","steps":["trace[1521493374] 'process raft request' (duration: 137.934886ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:47:51.107972Z","caller":"traceutil/trace.go:171","msg":"trace[13614779] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"134.844781ms","start":"2026-03-09T01:47:50.973115Z","end":"2026-03-09T01:47:51.10796Z","steps":["trace[13614779] 'process raft request' (duration: 133.671706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:47:59.293155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.18799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T01:47:59.293336Z","caller":"traceutil/trace.go:171","msg":"trace[1573748672] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"141.316297ms","start":"2026-03-09T01:47:59.151982Z","end":"2026-03-09T01:47:59.293299Z","steps":["trace[1573748672] 'process raft request' (duration: 36.851681ms)","trace[1573748672] 'compare' (duration: 104.004667ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:47:59.293468Z","caller":"traceutil/trace.go:171","msg":"trace[485256249] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"137.547408ms","start":"2026-03-09T01:47:59.155891Z","end":"2026-03-09T01:47:59.293438Z","steps":["trace[485256249] 'process raft request' (duration: 137.425856ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:47:59.293696Z","caller":"traceutil/trace.go:171","msg":"trace[1418007308] linearizableReadLoop","detail":"{readStateIndex:5161; appliedIndex:5160; }","duration":"139.775365ms","start":"2026-03-09T01:47:59.153911Z","end":"2026-03-09T01:47:59.293686Z","steps":["trace[1418007308] 'read index received' (duration: 34.930391ms)","trace[1418007308] 'applied index is now lower than readState.Index' (duration: 104.843634ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:47:59.293889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.964159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6m99z\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-09T01:47:59.293915Z","caller":"traceutil/trace.go:171","msg":"trace[1403027399] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6m99z; range_end:; response_count:1; response_revision:4976; }","duration":"140.038171ms","start":"2026-03-09T01:47:59.153869Z","end":"2026-03-09T01:47:59.293907Z","steps":["trace[1403027399] 'agreement among raft nodes before linearized reading' (duration: 139.851867ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:49:22.309065Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2721} {"level":"info","ts":"2026-03-09T01:49:22.370416Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2721,"took":"59.725757ms","hash":1713892245,"current-db-size-bytes":40648704,"current-db-size":"41 MB","current-db-size-in-use-bytes":30093312,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-09T01:49:22.370522Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1713892245,"revision":2721,"compact-revision":-1} {"level":"info","ts":"2026-03-09T01:54:22.323917Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5601} {"level":"info","ts":"2026-03-09T01:54:22.41376Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5601,"took":"86.777693ms","hash":4131751290,"current-db-size-bytes":41775104,"current-db-size":"42 MB","current-db-size-in-use-bytes":21991424,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-03-09T01:54:22.413863Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4131751290,"revision":5601,"compact-revision":2721} {"level":"info","ts":"2026-03-09T01:54:27.183198Z","caller":"traceutil/trace.go:171","msg":"trace[1076129446] linearizableReadLoop","detail":"{readStateIndex:7994; appliedIndex:7993; }","duration":"146.150823ms","start":"2026-03-09T01:54:27.037024Z","end":"2026-03-09T01:54:27.183175Z","steps":["trace[1076129446] 'read index received' (duration: 146.01861ms)","trace[1076129446] 'applied index is now lower than readState.Index' (duration: 131.083µs)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:54:27.183369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.336526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:54:27.183394Z","caller":"traceutil/trace.go:171","msg":"trace[1444410375] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:7714; }","duration":"146.381937ms","start":"2026-03-09T01:54:27.037005Z","end":"2026-03-09T01:54:27.183387Z","steps":["trace[1444410375] 'agreement among raft nodes before linearized reading' (duration: 146.257575ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:54:27.183719Z","caller":"traceutil/trace.go:171","msg":"trace[1660067422] transaction","detail":"{read_only:false; response_revision:7714; number_of_response:1; }","duration":"213.660346ms","start":"2026-03-09T01:54:26.970046Z","end":"2026-03-09T01:54:27.183707Z","steps":["trace[1660067422] 'process raft request' (duration: 213.007612ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:54:27.371433Z","caller":"traceutil/trace.go:171","msg":"trace[1000761706] transaction","detail":"{read_only:false; response_revision:7715; number_of_response:1; }","duration":"120.726173ms","start":"2026-03-09T01:54:27.250668Z","end":"2026-03-09T01:54:27.371394Z","steps":["trace[1000761706] 'process raft request' (duration: 116.595586ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:54:38.184702Z","caller":"traceutil/trace.go:171","msg":"trace[26898220] transaction","detail":"{read_only:false; response_revision:7768; number_of_response:1; }","duration":"289.000616ms","start":"2026-03-09T01:54:37.89566Z","end":"2026-03-09T01:54:38.184661Z","steps":["trace[26898220] 'process raft request' (duration: 251.394428ms)","trace[26898220] 'compare' (duration: 37.459905ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:54:39.736281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.327978ms","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-03-09T01:54:39.736938Z","caller":"traceutil/trace.go:171","msg":"trace[1013208767] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7773; }","duration":"115.030883ms","start":"2026-03-09T01:54:39.621876Z","end":"2026-03-09T01:54:39.736906Z","steps":["trace[1013208767] 'range keys from in-memory index tree' (duration: 114.100693ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:55:04.100226Z","caller":"traceutil/trace.go:171","msg":"trace[765272773] transaction","detail":"{read_only:false; response_revision:7944; number_of_response:1; }","duration":"101.004195ms","start":"2026-03-09T01:55:03.999192Z","end":"2026-03-09T01:55:04.100197Z","steps":["trace[765272773] 'process raft request' (duration: 100.544425ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:55:06.935946Z","caller":"traceutil/trace.go:171","msg":"trace[1274744486] transaction","detail":"{read_only:false; response_revision:7961; number_of_response:1; }","duration":"452.304043ms","start":"2026-03-09T01:55:06.483609Z","end":"2026-03-09T01:55:06.935913Z","steps":["trace[1274744486] 'process raft request' (duration: 452.055938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:55:06.936137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:55:06.483594Z","time spent":"452.451006ms","remote":"127.0.0.1:52436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19420,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T01:55:06.937141Z","caller":"traceutil/trace.go:171","msg":"trace[877252304] linearizableReadLoop","detail":"{readStateIndex:8252; appliedIndex:8252; }","duration":"326.591814ms","start":"2026-03-09T01:55:06.610531Z","end":"2026-03-09T01:55:06.937122Z","steps":["trace[877252304] 'read index received' (duration: 326.582984ms)","trace[877252304] 'applied index is now lower than readState.Index' (duration: 6.83µs)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:55:06.937254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.711257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:55:06.937294Z","caller":"traceutil/trace.go:171","msg":"trace[343647739] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:7961; }","duration":"326.787168ms","start":"2026-03-09T01:55:06.610495Z","end":"2026-03-09T01:55:06.937282Z","steps":["trace[343647739] 'agreement among raft nodes before linearized reading' (duration: 326.711947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:55:06.937382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:55:06.610483Z","time spent":"326.886951ms","remote":"127.0.0.1:42270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-09T01:55:06.941124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.413513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-03-09T01:55:06.941189Z","caller":"traceutil/trace.go:171","msg":"trace[4960154] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7962; }","duration":"175.524926ms","start":"2026-03-09T01:55:06.765652Z","end":"2026-03-09T01:55:06.941177Z","steps":["trace[4960154] 'agreement among raft nodes before linearized reading' (duration: 175.343062ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:55:06.941533Z","caller":"traceutil/trace.go:171","msg":"trace[1122205235] transaction","detail":"{read_only:false; response_revision:7962; number_of_response:1; }","duration":"194.585601ms","start":"2026-03-09T01:55:06.746932Z","end":"2026-03-09T01:55:06.941518Z","steps":["trace[1122205235] 'process raft request' (duration: 193.986968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:55:06.941726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.531957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:55:06.941759Z","caller":"traceutil/trace.go:171","msg":"trace[843814313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7962; }","duration":"111.613429ms","start":"2026-03-09T01:55:06.830133Z","end":"2026-03-09T01:55:06.941747Z","steps":["trace[843814313] 'agreement among raft nodes before linearized reading' (duration: 111.558447ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:55:19.992979Z","caller":"traceutil/trace.go:171","msg":"trace[328289428] transaction","detail":"{read_only:false; response_revision:8061; number_of_response:1; }","duration":"136.236942ms","start":"2026-03-09T01:55:19.856715Z","end":"2026-03-09T01:55:19.992952Z","steps":["trace[328289428] 'process raft request' (duration: 136.076398ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:55:20.005525Z","caller":"traceutil/trace.go:171","msg":"trace[641621875] linearizableReadLoop","detail":"{readStateIndex:8357; appliedIndex:8356; }","duration":"113.290276ms","start":"2026-03-09T01:55:19.892204Z","end":"2026-03-09T01:55:20.005494Z","steps":["trace[641621875] 'read index received' (duration: 101.496185ms)","trace[641621875] 'applied index is now lower than readState.Index' (duration: 11.792651ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:55:20.005933Z","caller":"traceutil/trace.go:171","msg":"trace[814786495] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8062; }","duration":"148.656627ms","start":"2026-03-09T01:55:19.857268Z","end":"2026-03-09T01:55:20.005925Z","steps":["trace[814786495] 'process raft request' (duration: 147.950082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:55:20.006079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.916219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-j869q\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-03-09T01:55:20.006146Z","caller":"traceutil/trace.go:171","msg":"trace[492934509] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-j869q; range_end:; response_count:1; response_revision:8063; }","duration":"114.036872ms","start":"2026-03-09T01:55:19.892093Z","end":"2026-03-09T01:55:20.00613Z","steps":["trace[492934509] 'agreement among raft nodes before linearized reading' (duration: 113.901249ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:55:20.006355Z","caller":"traceutil/trace.go:171","msg":"trace[803154822] transaction","detail":"{read_only:false; response_revision:8063; number_of_response:1; }","duration":"113.983551ms","start":"2026-03-09T01:55:19.892359Z","end":"2026-03-09T01:55:20.006343Z","steps":["trace[803154822] 'process raft request' (duration: 113.063931ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:58:42.019742Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cf8c3a138c9a77a2","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-09T01:58:42.026917Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-03-09T01:58:42.027021Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-03-09T01:59:22.332037Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7606} {"level":"info","ts":"2026-03-09T01:59:22.396088Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7606,"took":"61.346244ms","hash":4018264008,"current-db-size-bytes":41775104,"current-db-size":"42 MB","current-db-size-in-use-bytes":25985024,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-09T01:59:22.396159Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4018264008,"revision":7606,"compact-revision":5601} {"level":"info","ts":"2026-03-09T02:04:22.33876Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9938} {"level":"info","ts":"2026-03-09T02:04:22.421984Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9938,"took":"81.035461ms","hash":660116377,"current-db-size-bytes":41775104,"current-db-size":"42 MB","current-db-size-in-use-bytes":17768448,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-03-09T02:04:22.422078Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":660116377,"revision":9938,"compact-revision":7606}