{"level":"warn","ts":"2026-05-11T05:36:23.078923Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T05:36:23.08067Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.33: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.33:2380","--initial-cluster=instance=https://199.204.45.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.33: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-05-11T05:36:23.08078Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T05:36:23.080796Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-05-11T05:36:23.080822Z","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-05-11T05:36:23.082205Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"]} {"level":"info","ts":"2026-05-11T05:36:23.082468Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.33: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-05-11T05:36:23.087848Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.706155ms"} {"level":"info","ts":"2026-05-11T05:36:23.100943Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e5729930cf7e1f","cluster-id":"14aeb8c3ec66b749"} {"level":"info","ts":"2026-05-11T05:36:23.10106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T05:36:23.101105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 0"} {"level":"info","ts":"2026-05-11T05:36:23.101153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e5729930cf7e1f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T05:36:23.101185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 1"} {"level":"info","ts":"2026-05-11T05:36:23.101257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"warn","ts":"2026-05-11T05:36:23.10773Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T05:36:23.111409Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T05:36:23.112534Z","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-05-11T05:36:23.115467Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e5729930cf7e1f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T05:36:23.115679Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e5729930cf7e1f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T05:36:23.116002Z","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-05-11T05:36:23.116092Z","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-05-11T05:36:23.116115Z","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-05-11T05:36:23.118883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"info","ts":"2026-05-11T05:36:23.119448Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","added-peer-id":"f1e5729930cf7e1f","added-peer-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-05-11T05:36:23.120658Z","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-05-11T05:36:23.120758Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-05-11T05:36:23.120811Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-05-11T05:36:23.121035Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e5729930cf7e1f","initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T05:36:23.121063Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T05:36:23.802197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T05:36:23.80232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T05:36:23.802344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgPreVoteResp from f1e5729930cf7e1f at term 1"} {"level":"info","ts":"2026-05-11T05:36:23.802421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became candidate at term 2"} {"level":"info","ts":"2026-05-11T05:36:23.802461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgVoteResp from f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-05-11T05:36:23.802539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became leader at term 2"} {"level":"info","ts":"2026-05-11T05:36:23.802567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e5729930cf7e1f elected leader f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-05-11T05:36:23.803488Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:36:23.804253Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T05:36:23.804254Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e5729930cf7e1f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.33:2379]}","request-path":"/0/members/f1e5729930cf7e1f/attributes","cluster-id":"14aeb8c3ec66b749","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T05:36:23.80459Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:36:23.80474Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:36:23.804708Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T05:36:23.804783Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:36:23.804982Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T05:36:23.805009Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T05:36:23.808493Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.33:2379"} {"level":"info","ts":"2026-05-11T05:36:23.811278Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-11T05:36:29.157969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.936591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:36:29.158273Z","caller":"traceutil/trace.go:171","msg":"trace[789111337] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"155.841673ms","start":"2026-05-11T05:36:29.002395Z","end":"2026-05-11T05:36:29.158237Z","steps":["trace[789111337] 'process raft request' (duration: 155.67614ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:36:29.158333Z","caller":"traceutil/trace.go:171","msg":"trace[924007670] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"334.82804ms","start":"2026-05-11T05:36:28.823479Z","end":"2026-05-11T05:36:29.158307Z","steps":["trace[924007670] 'process raft request' (duration: 43.176407ms)","trace[924007670] 'compare' (duration: 290.739128ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:36:29.158445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:36:28.823467Z","time spent":"334.929302ms","remote":"127.0.0.1:41750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:36:29.601799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.170746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:36:29.6021Z","caller":"traceutil/trace.go:171","msg":"trace[1668815690] linearizableReadLoop","detail":"{readStateIndex:300; appliedIndex:299; }","duration":"371.002382ms","start":"2026-05-11T05:36:29.231074Z","end":"2026-05-11T05:36:29.602076Z","steps":["trace[1668815690] 'read index received' (duration: 177.49157ms)","trace[1668815690] 'applied index is now lower than readState.Index' (duration: 193.508422ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:36:29.602183Z","caller":"traceutil/trace.go:171","msg":"trace[1053044706] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"440.488581ms","start":"2026-05-11T05:36:29.16166Z","end":"2026-05-11T05:36:29.602149Z","steps":["trace[1053044706] 'process raft request' (duration: 246.893587ms)","trace[1053044706] 'compare' (duration: 192.628757ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:36:29.602379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.196375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-05-11T05:36:29.602402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:36:29.161647Z","time spent":"440.618632ms","remote":"127.0.0.1:41884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:36:29.602459Z","caller":"traceutil/trace.go:171","msg":"trace[331938970] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:292; }","duration":"371.404168ms","start":"2026-05-11T05:36:29.231038Z","end":"2026-05-11T05:36:29.602442Z","steps":["trace[331938970] 'agreement among raft nodes before linearized reading' (duration: 371.121714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:36:29.602506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:36:29.231019Z","time spent":"371.47529ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-11T05:36:29.603072Z","caller":"traceutil/trace.go:171","msg":"trace[1772459020] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"371.809287ms","start":"2026-05-11T05:36:29.231242Z","end":"2026-05-11T05:36:29.603051Z","steps":["trace[1772459020] 'process raft request' (duration: 371.711135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:36:29.603169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:36:29.23122Z","time spent":"371.899618ms","remote":"127.0.0.1:41628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:36:29.611946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.466581ms","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-05-11T05:36:29.612023Z","caller":"traceutil/trace.go:171","msg":"trace[734133146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:293; }","duration":"380.541164ms","start":"2026-05-11T05:36:29.231441Z","end":"2026-05-11T05:36:29.611982Z","steps":["trace[734133146] 'agreement among raft nodes before linearized reading' (duration: 380.463182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:36:29.612046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:36:29.23135Z","time spent":"380.691396ms","remote":"127.0.0.1:41804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T05:36:29.612299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.187917ms","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-05-11T05:36:29.61232Z","caller":"traceutil/trace.go:171","msg":"trace[149085917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:293; }","duration":"278.245968ms","start":"2026-05-11T05:36:29.334068Z","end":"2026-05-11T05:36:29.612314Z","steps":["trace[149085917] 'agreement among raft nodes before linearized reading' (duration: 278.206288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:36:29.612395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.772151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:36:29.612418Z","caller":"traceutil/trace.go:171","msg":"trace[1618127385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:293; }","duration":"290.838662ms","start":"2026-05-11T05:36:29.321574Z","end":"2026-05-11T05:36:29.612413Z","steps":["trace[1618127385] 'agreement among raft nodes before linearized reading' (duration: 290.806832ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:36:55.838141Z","caller":"traceutil/trace.go:171","msg":"trace[2126257275] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"166.936649ms","start":"2026-05-11T05:36:55.671171Z","end":"2026-05-11T05:36:55.838108Z","steps":["trace[2126257275] 'process raft request' (duration: 166.698726ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:36:55.950091Z","caller":"traceutil/trace.go:171","msg":"trace[1753819984] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"140.801732ms","start":"2026-05-11T05:36:55.809256Z","end":"2026-05-11T05:36:55.950057Z","steps":["trace[1753819984] 'read index received' (duration: 29.176273ms)","trace[1753819984] 'applied index is now lower than readState.Index' (duration: 111.621179ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:36:55.950534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.259628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-11T05:36:55.950602Z","caller":"traceutil/trace.go:171","msg":"trace[1530514338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"141.379961ms","start":"2026-05-11T05:36:55.809206Z","end":"2026-05-11T05:36:55.950586Z","steps":["trace[1530514338] 'agreement among raft nodes before linearized reading' (duration: 141.117846ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:36:55.950857Z","caller":"traceutil/trace.go:171","msg":"trace[1048003998] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"217.175425ms","start":"2026-05-11T05:36:55.733666Z","end":"2026-05-11T05:36:55.950841Z","steps":["trace[1048003998] 'process raft request' (duration: 211.150085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:36:55.95107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.191628ms","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-05-11T05:36:55.951124Z","caller":"traceutil/trace.go:171","msg":"trace[59941026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"100.328701ms","start":"2026-05-11T05:36:55.850784Z","end":"2026-05-11T05:36:55.951112Z","steps":["trace[59941026] 'agreement among raft nodes before linearized reading' (duration: 100.202908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:36:56.630155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.676197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:36:56.63058Z","caller":"traceutil/trace.go:171","msg":"trace[1801990689] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"621.811555ms","start":"2026-05-11T05:36:56.008739Z","end":"2026-05-11T05:36:56.630551Z","steps":["trace[1801990689] 'process raft request' (duration: 373.655801ms)","trace[1801990689] 'compare' (duration: 247.123639ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:36:56.63072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:36:56.008722Z","time spent":"621.930757ms","remote":"127.0.0.1:42056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:36:56.630741Z","caller":"traceutil/trace.go:171","msg":"trace[281362462] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"571.967217ms","start":"2026-05-11T05:36:56.058754Z","end":"2026-05-11T05:36:56.630721Z","steps":["trace[281362462] 'process raft request' (duration: 571.522861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:36:56.631047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:36:56.058683Z","time spent":"572.095428ms","remote":"127.0.0.1:41804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:37:15.465037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.646209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-11T05:37:15.465195Z","caller":"traceutil/trace.go:171","msg":"trace[847266442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:783; }","duration":"343.923244ms","start":"2026-05-11T05:37:15.121246Z","end":"2026-05-11T05:37:15.46517Z","steps":["trace[847266442] 'range keys from in-memory index tree' (duration: 343.419217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:37:15.465247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:37:15.121221Z","time spent":"344.014705ms","remote":"127.0.0.1:41804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T05:37:58.588937Z","caller":"traceutil/trace.go:171","msg":"trace[601177581] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"419.165006ms","start":"2026-05-11T05:37:58.169749Z","end":"2026-05-11T05:37:58.588914Z","steps":["trace[601177581] 'process raft request' (duration: 418.938901ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:58.588919Z","caller":"traceutil/trace.go:171","msg":"trace[1486802264] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1091; }","duration":"324.44929ms","start":"2026-05-11T05:37:58.264426Z","end":"2026-05-11T05:37:58.588875Z","steps":["trace[1486802264] 'read index received' (duration: 324.206855ms)","trace[1486802264] 'applied index is now lower than readState.Index' (duration: 241.225µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:37:58.589065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.629615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T05:37:58.589241Z","caller":"traceutil/trace.go:171","msg":"trace[700439712] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1056; }","duration":"324.854179ms","start":"2026-05-11T05:37:58.264374Z","end":"2026-05-11T05:37:58.589228Z","steps":["trace[700439712] 'agreement among raft nodes before linearized reading' (duration: 324.609533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:37:58.589288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:37:58.264354Z","time spent":"324.921821ms","remote":"127.0.0.1:41874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":69,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:37:58.589415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.13ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-11T05:37:58.589105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:37:58.169725Z","time spent":"419.283979ms","remote":"127.0.0.1:41804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:37:58.589641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.55288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-11T05:37:58.589652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.240109ms","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-05-11T05:37:58.5897Z","caller":"traceutil/trace.go:171","msg":"trace[617881313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1056; }","duration":"202.637531ms","start":"2026-05-11T05:37:58.387051Z","end":"2026-05-11T05:37:58.589689Z","steps":["trace[617881313] 'agreement among raft nodes before linearized reading' (duration: 202.323414ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:58.589707Z","caller":"traceutil/trace.go:171","msg":"trace[861167394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1056; }","duration":"211.375241ms","start":"2026-05-11T05:37:58.378319Z","end":"2026-05-11T05:37:58.589694Z","steps":["trace[861167394] 'agreement among raft nodes before linearized reading' (duration: 211.074244ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:58.589516Z","caller":"traceutil/trace.go:171","msg":"trace[1138862392] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1056; }","duration":"279.276634ms","start":"2026-05-11T05:37:58.310219Z","end":"2026-05-11T05:37:58.589496Z","steps":["trace[1138862392] 'agreement among raft nodes before linearized reading' (duration: 279.058378ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:38:02.728517Z","caller":"traceutil/trace.go:171","msg":"trace[759499717] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"111.896397ms","start":"2026-05-11T05:38:02.616322Z","end":"2026-05-11T05:38:02.728218Z","steps":["trace[759499717] 'process raft request' (duration: 110.590557ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:38:02.728913Z","caller":"traceutil/trace.go:171","msg":"trace[219707350] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"111.688253ms","start":"2026-05-11T05:38:02.617204Z","end":"2026-05-11T05:38:02.728893Z","steps":["trace[219707350] 'process raft request' (duration: 111.351185ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:38:02.729011Z","caller":"traceutil/trace.go:171","msg":"trace[498899848] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"110.349091ms","start":"2026-05-11T05:38:02.618655Z","end":"2026-05-11T05:38:02.729004Z","steps":["trace[498899848] 'process raft request' (duration: 109.998653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:11.450781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.251908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:39:11.450911Z","caller":"traceutil/trace.go:171","msg":"trace[600106583] linearizableReadLoop","detail":"{readStateIndex:1562; appliedIndex:1561; }","duration":"175.178774ms","start":"2026-05-11T05:39:11.275698Z","end":"2026-05-11T05:39:11.450877Z","steps":["trace[600106583] 'read index received' (duration: 5.655091ms)","trace[600106583] 'applied index is now lower than readState.Index' (duration: 169.522063ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:39:11.45102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.296416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:39:11.451054Z","caller":"traceutil/trace.go:171","msg":"trace[147962209] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:0; response_revision:1511; }","duration":"175.386067ms","start":"2026-05-11T05:39:11.275655Z","end":"2026-05-11T05:39:11.451041Z","steps":["trace[147962209] 'agreement among raft nodes before linearized reading' (duration: 175.294315ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:11.451068Z","caller":"traceutil/trace.go:171","msg":"trace[1496014192] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"227.558408ms","start":"2026-05-11T05:39:11.223474Z","end":"2026-05-11T05:39:11.451032Z","steps":["trace[1496014192] 'process raft request' (duration: 57.942533ms)","trace[1496014192] 'compare' (duration: 168.935983ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:39:15.065525Z","caller":"traceutil/trace.go:171","msg":"trace[1481568322] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"192.029247ms","start":"2026-05-11T05:39:14.873469Z","end":"2026-05-11T05:39:15.065498Z","steps":["trace[1481568322] 'process raft request' (duration: 189.279618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:16.576462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.332019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:39:16.576708Z","caller":"traceutil/trace.go:171","msg":"trace[925620165] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"323.118231ms","start":"2026-05-11T05:39:16.253575Z","end":"2026-05-11T05:39:16.576694Z","steps":["trace[925620165] 'process raft request' (duration: 97.494068ms)","trace[925620165] 'compare' (duration: 225.105344ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:39:16.576752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:16.253557Z","time spent":"323.173832ms","remote":"127.0.0.1:41780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3148,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:39:25.298821Z","caller":"traceutil/trace.go:171","msg":"trace[1590505403] transaction","detail":"{read_only:false; response_revision:1734; number_of_response:1; }","duration":"150.371016ms","start":"2026-05-11T05:39:25.14843Z","end":"2026-05-11T05:39:25.298801Z","steps":["trace[1590505403] 'process raft request' (duration: 150.151692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:25.743017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.228078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:39:25.74331Z","caller":"traceutil/trace.go:171","msg":"trace[1473398362] linearizableReadLoop","detail":"{readStateIndex:1794; appliedIndex:1791; }","duration":"408.677814ms","start":"2026-05-11T05:39:25.334604Z","end":"2026-05-11T05:39:25.743282Z","steps":["trace[1473398362] 'read index received' (duration: 277.962178ms)","trace[1473398362] 'applied index is now lower than readState.Index' (duration: 130.714526ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:39:25.743424Z","caller":"traceutil/trace.go:171","msg":"trace[1417241632] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"438.672271ms","start":"2026-05-11T05:39:25.30474Z","end":"2026-05-11T05:39:25.743412Z","steps":["trace[1417241632] 'process raft request' (duration: 438.500589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:25.743495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:25.304727Z","time spent":"438.740123ms","remote":"127.0.0.1:41804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:39:25.743421Z","caller":"traceutil/trace.go:171","msg":"trace[2247380] transaction","detail":"{read_only:false; response_revision:1737; number_of_response:1; }","duration":"440.230969ms","start":"2026-05-11T05:39:25.303124Z","end":"2026-05-11T05:39:25.743355Z","steps":["trace[2247380] 'process raft request' (duration: 440.040155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:25.743765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:25.303109Z","time spent":"440.631405ms","remote":"127.0.0.1:41804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:39:25.743294Z","caller":"traceutil/trace.go:171","msg":"trace[2018035535] transaction","detail":"{read_only:false; response_revision:1736; number_of_response:1; }","duration":"440.141077ms","start":"2026-05-11T05:39:25.303124Z","end":"2026-05-11T05:39:25.743266Z","steps":["trace[2018035535] 'process raft request' (duration: 309.571864ms)","trace[2018035535] 'compare' (duration: 130.081524ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:39:25.743912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:25.303116Z","time spent":"440.770758ms","remote":"127.0.0.1:41804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:39:25.743969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.134651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T05:39:25.744066Z","caller":"traceutil/trace.go:171","msg":"trace[269913833] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1738; }","duration":"329.296284ms","start":"2026-05-11T05:39:25.414748Z","end":"2026-05-11T05:39:25.744044Z","steps":["trace[269913833] 'agreement among raft nodes before linearized reading' (duration: 329.132332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:25.74422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:25.414714Z","time spent":"329.488088ms","remote":"127.0.0.1:42024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":10,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:39:25.744331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.616091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:39:25.74439Z","caller":"traceutil/trace.go:171","msg":"trace[758012676] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1738; }","duration":"409.826433ms","start":"2026-05-11T05:39:25.334551Z","end":"2026-05-11T05:39:25.744377Z","steps":["trace[758012676] 'agreement among raft nodes before linearized reading' (duration: 409.612519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:25.744505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:25.334523Z","time spent":"409.965916ms","remote":"127.0.0.1:41942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-05-11T05:39:25.745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.356307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:39:25.745098Z","caller":"traceutil/trace.go:171","msg":"trace[503958807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1738; }","duration":"204.980949ms","start":"2026-05-11T05:39:25.540095Z","end":"2026-05-11T05:39:25.745076Z","steps":["trace[503958807] 'agreement among raft nodes before linearized reading' (duration: 204.302316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:26.339375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.895775ms","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":"info","ts":"2026-05-11T05:39:26.339456Z","caller":"traceutil/trace.go:171","msg":"trace[1974340619] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1738; }","duration":"124.024107ms","start":"2026-05-11T05:39:26.215415Z","end":"2026-05-11T05:39:26.339439Z","steps":["trace[1974340619] 'count revisions from in-memory index tree' (duration: 123.793053ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:39.917824Z","caller":"traceutil/trace.go:171","msg":"trace[1221662544] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"109.019871ms","start":"2026-05-11T05:39:39.808774Z","end":"2026-05-11T05:39:39.917794Z","steps":["trace[1221662544] 'process raft request' (duration: 30.639684ms)","trace[1221662544] 'compare' (duration: 78.130243ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:39:39.917903Z","caller":"traceutil/trace.go:171","msg":"trace[750097533] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"108.654656ms","start":"2026-05-11T05:39:39.809239Z","end":"2026-05-11T05:39:39.917893Z","steps":["trace[750097533] 'process raft request' (duration: 108.459422ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:39.917855Z","caller":"traceutil/trace.go:171","msg":"trace[622285974] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"103.684882ms","start":"2026-05-11T05:39:39.814154Z","end":"2026-05-11T05:39:39.917839Z","steps":["trace[622285974] 'process raft request' (duration: 103.620602ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:39.918215Z","caller":"traceutil/trace.go:171","msg":"trace[1867625614] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"104.70192ms","start":"2026-05-11T05:39:39.813505Z","end":"2026-05-11T05:39:39.918207Z","steps":["trace[1867625614] 'process raft request' (duration: 104.226941ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:40.326558Z","caller":"traceutil/trace.go:171","msg":"trace[1781183192] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"185.360569ms","start":"2026-05-11T05:39:40.141176Z","end":"2026-05-11T05:39:40.326536Z","steps":["trace[1781183192] 'process raft request' (duration: 185.214937ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:40.648331Z","caller":"traceutil/trace.go:171","msg":"trace[1187959721] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"150.19946ms","start":"2026-05-11T05:39:40.498096Z","end":"2026-05-11T05:39:40.648296Z","steps":["trace[1187959721] 'process raft request' (duration: 149.985777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:40.64903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.599501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/openstack/rabbitmq-cluster-operator-54f767cff8\" ","response":"range_response_count:1 size:5558"} {"level":"info","ts":"2026-05-11T05:39:40.649097Z","caller":"traceutil/trace.go:171","msg":"trace[1392987960] range","detail":"{range_begin:/registry/replicasets/openstack/rabbitmq-cluster-operator-54f767cff8; range_end:; response_count:1; response_revision:1994; }","duration":"149.697211ms","start":"2026-05-11T05:39:40.499382Z","end":"2026-05-11T05:39:40.649079Z","steps":["trace[1392987960] 'agreement among raft nodes before linearized reading' (duration: 149.520499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:40.64944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.152636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:39:40.649503Z","caller":"traceutil/trace.go:171","msg":"trace[1219514840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1994; }","duration":"111.274716ms","start":"2026-05-11T05:39:40.538216Z","end":"2026-05-11T05:39:40.649491Z","steps":["trace[1219514840] 'agreement among raft nodes before linearized reading' (duration: 110.655946ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:40.806823Z","caller":"traceutil/trace.go:171","msg":"trace[457006252] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"143.013409ms","start":"2026-05-11T05:39:40.663776Z","end":"2026-05-11T05:39:40.80679Z","steps":["trace[457006252] 'process raft request' (duration: 142.714564ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:40.806933Z","caller":"traceutil/trace.go:171","msg":"trace[981150667] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"115.750612ms","start":"2026-05-11T05:39:40.691161Z","end":"2026-05-11T05:39:40.806912Z","steps":["trace[981150667] 'process raft request' (duration: 115.486917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:40.80711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.411969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:6138"} {"level":"info","ts":"2026-05-11T05:39:40.807184Z","caller":"traceutil/trace.go:171","msg":"trace[1300626096] range","detail":"{range_begin:/registry/deployments/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:1998; }","duration":"148.496101ms","start":"2026-05-11T05:39:40.658669Z","end":"2026-05-11T05:39:40.807165Z","steps":["trace[1300626096] 'agreement among raft nodes before linearized reading' (duration: 148.249507ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:40.894751Z","caller":"traceutil/trace.go:171","msg":"trace[163584237] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"166.26707ms","start":"2026-05-11T05:39:40.728442Z","end":"2026-05-11T05:39:40.894709Z","steps":["trace[163584237] 'process raft request' (duration: 166.168118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:40.894822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.31499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1082"} {"level":"info","ts":"2026-05-11T05:39:40.894886Z","caller":"traceutil/trace.go:171","msg":"trace[74352609] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:2000; }","duration":"166.399163ms","start":"2026-05-11T05:39:40.72847Z","end":"2026-05-11T05:39:40.894869Z","steps":["trace[74352609] 'agreement among raft nodes before linearized reading' (duration: 166.217509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:40.895002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.499663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:1114"} {"level":"info","ts":"2026-05-11T05:39:40.895155Z","caller":"traceutil/trace.go:171","msg":"trace[868972579] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:2000; }","duration":"166.682927ms","start":"2026-05-11T05:39:40.728431Z","end":"2026-05-11T05:39:40.895114Z","steps":["trace[868972579] 'agreement among raft nodes before linearized reading' (duration: 166.3108ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:40.895213Z","caller":"traceutil/trace.go:171","msg":"trace[1835379750] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"170.721493ms","start":"2026-05-11T05:39:40.724453Z","end":"2026-05-11T05:39:40.895174Z","steps":["trace[1835379750] 'process raft request' (duration: 162.630217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:40.895481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.411778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:4 size:230818"} {"level":"info","ts":"2026-05-11T05:39:40.895534Z","caller":"traceutil/trace.go:171","msg":"trace[1413126219] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:4; response_revision:2000; }","duration":"170.48019ms","start":"2026-05-11T05:39:40.72504Z","end":"2026-05-11T05:39:40.89552Z","steps":["trace[1413126219] 'agreement among raft nodes before linearized reading' (duration: 170.106173ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:41.084768Z","caller":"traceutil/trace.go:171","msg":"trace[1180560123] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"120.750754ms","start":"2026-05-11T05:39:40.963988Z","end":"2026-05-11T05:39:41.084739Z","steps":["trace[1180560123] 'process raft request' (duration: 113.710266ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:46.721787Z","caller":"traceutil/trace.go:171","msg":"trace[1353732099] linearizableReadLoop","detail":"{readStateIndex:2234; appliedIndex:2233; }","duration":"105.474462ms","start":"2026-05-11T05:39:46.616278Z","end":"2026-05-11T05:39:46.721752Z","steps":["trace[1353732099] 'read index received' (duration: 41.397668ms)","trace[1353732099] 'applied index is now lower than readState.Index' (duration: 64.075524ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:39:46.721883Z","caller":"traceutil/trace.go:171","msg":"trace[375017128] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"106.484769ms","start":"2026-05-11T05:39:46.615369Z","end":"2026-05-11T05:39:46.721854Z","steps":["trace[375017128] 'process raft request' (duration: 42.284402ms)","trace[375017128] 'compare' (duration: 63.982163ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:39:46.721963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.668375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:39:46.722002Z","caller":"traceutil/trace.go:171","msg":"trace[1017660265] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:0; response_revision:2144; }","duration":"105.743147ms","start":"2026-05-11T05:39:46.616247Z","end":"2026-05-11T05:39:46.72199Z","steps":["trace[1017660265] 'agreement among raft nodes before linearized reading' (duration: 105.644315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:46.722198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.753267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T05:39:46.722242Z","caller":"traceutil/trace.go:171","msg":"trace[1574304600] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2144; }","duration":"102.823918ms","start":"2026-05-11T05:39:46.619406Z","end":"2026-05-11T05:39:46.72223Z","steps":["trace[1574304600] 'agreement among raft nodes before linearized reading' (duration: 102.627334ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:39:50.287748Z","caller":"traceutil/trace.go:171","msg":"trace[506833840] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"204.808312ms","start":"2026-05-11T05:39:50.082907Z","end":"2026-05-11T05:39:50.287715Z","steps":["trace[506833840] 'process raft request' (duration: 118.522847ms)","trace[506833840] 'compare' (duration: 86.00192ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:39:51.257616Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088156388392718123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:39:51.373396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.682355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-gwtfs\" ","response":"range_response_count:1 size:5814"} {"level":"info","ts":"2026-05-11T05:39:51.373553Z","caller":"traceutil/trace.go:171","msg":"trace[690974092] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-gwtfs; range_end:; response_count:1; response_revision:2246; }","duration":"976.874898ms","start":"2026-05-11T05:39:50.396653Z","end":"2026-05-11T05:39:51.373528Z","steps":["trace[690974092] 'range keys from in-memory index tree' (duration: 976.511363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:51.373626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:50.396635Z","time spent":"976.977861ms","remote":"127.0.0.1:41732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":5837,"request content":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-gwtfs\" "} {"level":"info","ts":"2026-05-11T05:39:51.393581Z","caller":"traceutil/trace.go:171","msg":"trace[2123285158] transaction","detail":"{read_only:false; response_revision:2247; number_of_response:1; }","duration":"880.568007ms","start":"2026-05-11T05:39:50.512993Z","end":"2026-05-11T05:39:51.393561Z","steps":["trace[2123285158] 'process raft request' (duration: 880.436285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:51.393757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:50.51297Z","time spent":"880.71948ms","remote":"127.0.0.1:41804","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-05-11T05:39:51.443238Z","caller":"traceutil/trace.go:171","msg":"trace[288012934] linearizableReadLoop","detail":"{readStateIndex:2342; appliedIndex:2340; }","duration":"686.088016ms","start":"2026-05-11T05:39:50.757125Z","end":"2026-05-11T05:39:51.443213Z","steps":["trace[288012934] 'read index received' (duration: 636.504429ms)","trace[288012934] 'applied index is now lower than readState.Index' (duration: 49.582867ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:39:51.443265Z","caller":"traceutil/trace.go:171","msg":"trace[778870361] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"677.115328ms","start":"2026-05-11T05:39:50.766123Z","end":"2026-05-11T05:39:51.443239Z","steps":["trace[778870361] 'process raft request' (duration: 677.024807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:51.443684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.551144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-11T05:39:51.443715Z","caller":"traceutil/trace.go:171","msg":"trace[1698525527] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2250; }","duration":"686.621875ms","start":"2026-05-11T05:39:50.757085Z","end":"2026-05-11T05:39:51.443707Z","steps":["trace[1698525527] 'agreement among raft nodes before linearized reading' (duration: 686.516144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:51.443736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:50.757069Z","time spent":"686.661365ms","remote":"127.0.0.1:41804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T05:39:51.44389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.404524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-42645270-c710-46e8-a282-c51f453f037e\" ","response":"range_response_count:1 size:3616"} {"level":"info","ts":"2026-05-11T05:39:51.443916Z","caller":"traceutil/trace.go:171","msg":"trace[89084263] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-42645270-c710-46e8-a282-c51f453f037e; range_end:; response_count:1; response_revision:2250; }","duration":"519.454736ms","start":"2026-05-11T05:39:50.924454Z","end":"2026-05-11T05:39:51.443909Z","steps":["trace[89084263] 'agreement among raft nodes before linearized reading' (duration: 519.387664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:51.443934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:50.924439Z","time spent":"519.490256ms","remote":"127.0.0.1:41732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3639,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-42645270-c710-46e8-a282-c51f453f037e\" "} {"level":"warn","ts":"2026-05-11T05:39:51.4439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:50.766094Z","time spent":"677.605846ms","remote":"127.0.0.1:41804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:39:51.444242Z","caller":"traceutil/trace.go:171","msg":"trace[1216682892] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"738.097995ms","start":"2026-05-11T05:39:50.706125Z","end":"2026-05-11T05:39:51.444223Z","steps":["trace[1216682892] 'process raft request' (duration: 736.918115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:51.444252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.941082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-11T05:39:51.444334Z","caller":"traceutil/trace.go:171","msg":"trace[1126447628] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2250; }","duration":"506.057244ms","start":"2026-05-11T05:39:50.938256Z","end":"2026-05-11T05:39:51.444313Z","steps":["trace[1126447628] 'agreement among raft nodes before linearized reading' (duration: 505.8149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:39:51.444361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:50.70609Z","time spent":"738.211276ms","remote":"127.0.0.1:41804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:39:51.444417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:39:50.938241Z","time spent":"506.161846ms","remote":"127.0.0.1:60256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-11T05:40:01.854415Z","caller":"traceutil/trace.go:171","msg":"trace[202252212] transaction","detail":"{read_only:false; response_revision:2371; number_of_response:1; }","duration":"240.568091ms","start":"2026-05-11T05:40:01.61383Z","end":"2026-05-11T05:40:01.854398Z","steps":["trace[202252212] 'process raft request' (duration: 201.78005ms)","trace[202252212] 'compare' (duration: 38.473026ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:40:02.447265Z","caller":"traceutil/trace.go:171","msg":"trace[103343629] transaction","detail":"{read_only:false; response_revision:2373; number_of_response:1; }","duration":"185.811307ms","start":"2026-05-11T05:40:02.261433Z","end":"2026-05-11T05:40:02.447245Z","steps":["trace[103343629] 'process raft request' (duration: 185.641205ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:02.550256Z","caller":"traceutil/trace.go:171","msg":"trace[1201578317] linearizableReadLoop","detail":"{readStateIndex:2471; appliedIndex:2470; }","duration":"103.009843ms","start":"2026-05-11T05:40:02.447211Z","end":"2026-05-11T05:40:02.550221Z","steps":["trace[1201578317] 'read index received' (duration: 7.904108ms)","trace[1201578317] 'applied index is now lower than readState.Index' (duration: 95.100185ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:40:02.550509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.933337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-11T05:40:02.550539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.21754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-11T05:40:02.550625Z","caller":"traceutil/trace.go:171","msg":"trace[1458483740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2374; }","duration":"163.348983ms","start":"2026-05-11T05:40:02.387255Z","end":"2026-05-11T05:40:02.550604Z","steps":["trace[1458483740] 'agreement among raft nodes before linearized reading' (duration: 163.10423ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:02.550643Z","caller":"traceutil/trace.go:171","msg":"trace[885155826] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2374; }","duration":"123.035888ms","start":"2026-05-11T05:40:02.42755Z","end":"2026-05-11T05:40:02.550586Z","steps":["trace[885155826] 'agreement among raft nodes before linearized reading' (duration: 122.734043ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:03.75255Z","caller":"traceutil/trace.go:171","msg":"trace[1280190196] linearizableReadLoop","detail":"{readStateIndex:2485; appliedIndex:2484; }","duration":"129.703694ms","start":"2026-05-11T05:40:03.622823Z","end":"2026-05-11T05:40:03.752527Z","steps":["trace[1280190196] 'read index received' (duration: 129.46003ms)","trace[1280190196] 'applied index is now lower than readState.Index' (duration: 242.434µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:40:03.752623Z","caller":"traceutil/trace.go:171","msg":"trace[1362627293] transaction","detail":"{read_only:false; response_revision:2387; number_of_response:1; }","duration":"139.767687ms","start":"2026-05-11T05:40:03.612818Z","end":"2026-05-11T05:40:03.752585Z","steps":["trace[1362627293] 'process raft request' (duration: 139.541923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:03.752871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.123743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-11T05:40:03.752947Z","caller":"traceutil/trace.go:171","msg":"trace[2029872629] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2387; }","duration":"108.256536ms","start":"2026-05-11T05:40:03.64467Z","end":"2026-05-11T05:40:03.752926Z","steps":["trace[2029872629] 'agreement among raft nodes before linearized reading' (duration: 108.030103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:03.753317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.482667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-d80087a2-f6b4-433f-8f7c-1f82c151c8d1\" ","response":"range_response_count:1 size:1303"} {"level":"info","ts":"2026-05-11T05:40:03.753369Z","caller":"traceutil/trace.go:171","msg":"trace[49052015] range","detail":"{range_begin:/registry/persistentvolumes/pvc-d80087a2-f6b4-433f-8f7c-1f82c151c8d1; range_end:; response_count:1; response_revision:2387; }","duration":"130.562957ms","start":"2026-05-11T05:40:03.622794Z","end":"2026-05-11T05:40:03.753356Z","steps":["trace[49052015] 'agreement among raft nodes before linearized reading' (duration: 129.859116ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:07.918248Z","caller":"traceutil/trace.go:171","msg":"trace[2073285802] linearizableReadLoop","detail":"{readStateIndex:2513; appliedIndex:2512; }","duration":"140.418237ms","start":"2026-05-11T05:40:07.777798Z","end":"2026-05-11T05:40:07.918217Z","steps":["trace[2073285802] 'read index received' (duration: 120.562525ms)","trace[2073285802] 'applied index is now lower than readState.Index' (duration: 19.854122ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:40:07.918302Z","caller":"traceutil/trace.go:171","msg":"trace[1274025392] transaction","detail":"{read_only:false; response_revision:2414; number_of_response:1; }","duration":"153.683121ms","start":"2026-05-11T05:40:07.764589Z","end":"2026-05-11T05:40:07.918272Z","steps":["trace[1274025392] 'process raft request' (duration: 133.78572ms)","trace[1274025392] 'compare' (duration: 19.611346ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:40:07.918549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.726151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-11T05:40:07.918609Z","caller":"traceutil/trace.go:171","msg":"trace[668200947] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2414; }","duration":"140.816604ms","start":"2026-05-11T05:40:07.777773Z","end":"2026-05-11T05:40:07.918589Z","steps":["trace[668200947] 'agreement among raft nodes before linearized reading' (duration: 140.630741ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:18.851815Z","caller":"traceutil/trace.go:171","msg":"trace[62307937] transaction","detail":"{read_only:false; response_revision:2486; number_of_response:1; }","duration":"101.453728ms","start":"2026-05-11T05:40:18.750341Z","end":"2026-05-11T05:40:18.851794Z","steps":["trace[62307937] 'process raft request' (duration: 47.446637ms)","trace[62307937] 'compare' (duration: 53.897229ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:40:25.06268Z","caller":"traceutil/trace.go:171","msg":"trace[1887524886] transaction","detail":"{read_only:false; response_revision:2532; number_of_response:1; }","duration":"183.556552ms","start":"2026-05-11T05:40:24.878959Z","end":"2026-05-11T05:40:25.062516Z","steps":["trace[1887524886] 'process raft request' (duration: 183.275447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:25.721262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.881023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:40:25.721485Z","caller":"traceutil/trace.go:171","msg":"trace[182123981] transaction","detail":"{read_only:false; response_revision:2535; number_of_response:1; }","duration":"221.492889ms","start":"2026-05-11T05:40:25.499946Z","end":"2026-05-11T05:40:25.721439Z","steps":["trace[182123981] 'process raft request' (duration: 27.375923ms)","trace[182123981] 'compare' (duration: 193.070419ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:40:26.462404Z","caller":"traceutil/trace.go:171","msg":"trace[1634351754] transaction","detail":"{read_only:false; response_revision:2541; number_of_response:1; }","duration":"163.056442ms","start":"2026-05-11T05:40:26.299313Z","end":"2026-05-11T05:40:26.462369Z","steps":["trace[1634351754] 'process raft request' (duration: 162.858768ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:27.143246Z","caller":"traceutil/trace.go:171","msg":"trace[676736329] linearizableReadLoop","detail":"{readStateIndex:2646; appliedIndex:2645; }","duration":"442.957723ms","start":"2026-05-11T05:40:26.700254Z","end":"2026-05-11T05:40:27.143211Z","steps":["trace[676736329] 'read index received' (duration: 442.704469ms)","trace[676736329] 'applied index is now lower than readState.Index' (duration: 252.364µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:40:27.14336Z","caller":"traceutil/trace.go:171","msg":"trace[54194120] transaction","detail":"{read_only:false; response_revision:2543; number_of_response:1; }","duration":"448.656723ms","start":"2026-05-11T05:40:26.694676Z","end":"2026-05-11T05:40:27.143333Z","steps":["trace[54194120] 'process raft request' (duration: 448.369839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:27.143758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.873317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-11T05:40:27.143811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:26.694654Z","time spent":"448.956137ms","remote":"127.0.0.1:41804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:40:27.14385Z","caller":"traceutil/trace.go:171","msg":"trace[410702306] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:2543; }","duration":"254.013431ms","start":"2026-05-11T05:40:26.889819Z","end":"2026-05-11T05:40:27.143832Z","steps":["trace[410702306] 'agreement among raft nodes before linearized reading' (duration: 253.824968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:27.143768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.498311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-11T05:40:27.144416Z","caller":"traceutil/trace.go:171","msg":"trace[1631076436] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2543; }","duration":"444.05276ms","start":"2026-05-11T05:40:26.700222Z","end":"2026-05-11T05:40:27.144275Z","steps":["trace[1631076436] 'agreement among raft nodes before linearized reading' (duration: 443.155086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:40:27.144566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:40:26.700209Z","time spent":"444.287283ms","remote":"127.0.0.1:41804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T05:40:27.381932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.937108ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-11T05:40:27.38242Z","caller":"traceutil/trace.go:171","msg":"trace[1477257306] transaction","detail":"{read_only:false; response_revision:2545; number_of_response:1; }","duration":"232.706932ms","start":"2026-05-11T05:40:27.149688Z","end":"2026-05-11T05:40:27.382395Z","steps":["trace[1477257306] 'process raft request' (duration: 232.632021ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:27.382587Z","caller":"traceutil/trace.go:171","msg":"trace[1620235094] transaction","detail":"{read_only:false; response_revision:2544; number_of_response:1; }","duration":"233.909831ms","start":"2026-05-11T05:40:27.148642Z","end":"2026-05-11T05:40:27.382552Z","steps":["trace[1620235094] 'process raft request' (duration: 233.427293ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:40:53.669716Z","caller":"traceutil/trace.go:171","msg":"trace[675436573] transaction","detail":"{read_only:false; response_revision:2688; number_of_response:1; }","duration":"128.724109ms","start":"2026-05-11T05:40:53.540954Z","end":"2026-05-11T05:40:53.669678Z","steps":["trace[675436573] 'process raft request' (duration: 128.517006ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:21.619837Z","caller":"traceutil/trace.go:171","msg":"trace[1865089800] transaction","detail":"{read_only:false; response_revision:2835; number_of_response:1; }","duration":"102.076384ms","start":"2026-05-11T05:41:21.517716Z","end":"2026-05-11T05:41:21.619792Z","steps":["trace[1865089800] 'process raft request' (duration: 101.80529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:58.079893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.355542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T05:41:58.07997Z","caller":"traceutil/trace.go:171","msg":"trace[1632504012] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3142; }","duration":"104.471594ms","start":"2026-05-11T05:41:57.975483Z","end":"2026-05-11T05:41:58.079955Z","steps":["trace[1632504012] 'range keys from in-memory index tree' (duration: 104.2363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:41:58.080167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.978456ms","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-05-11T05:41:58.080207Z","caller":"traceutil/trace.go:171","msg":"trace[1648185636] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3142; }","duration":"129.058926ms","start":"2026-05-11T05:41:57.951137Z","end":"2026-05-11T05:41:58.080196Z","steps":["trace[1648185636] 'range keys from in-memory index tree' (duration: 128.836054ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:58.207626Z","caller":"traceutil/trace.go:171","msg":"trace[1765347717] transaction","detail":"{read_only:false; response_revision:3143; number_of_response:1; }","duration":"122.841372ms","start":"2026-05-11T05:41:58.084762Z","end":"2026-05-11T05:41:58.207604Z","steps":["trace[1765347717] 'process raft request' (duration: 122.70054ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:58.265981Z","caller":"traceutil/trace.go:171","msg":"trace[2054857559] transaction","detail":"{read_only:false; response_revision:3144; number_of_response:1; }","duration":"181.183416ms","start":"2026-05-11T05:41:58.08477Z","end":"2026-05-11T05:41:58.265953Z","steps":["trace[2054857559] 'process raft request' (duration: 180.946743ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:58.26606Z","caller":"traceutil/trace.go:171","msg":"trace[78992145] linearizableReadLoop","detail":"{readStateIndex:3268; appliedIndex:3266; }","duration":"176.958703ms","start":"2026-05-11T05:41:58.08909Z","end":"2026-05-11T05:41:58.266049Z","steps":["trace[78992145] 'read index received' (duration: 119.045165ms)","trace[78992145] 'applied index is now lower than readState.Index' (duration: 57.912938ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:41:58.266195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.090985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-05-11T05:41:58.266227Z","caller":"traceutil/trace.go:171","msg":"trace[1237082121] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3145; }","duration":"177.175386ms","start":"2026-05-11T05:41:58.089044Z","end":"2026-05-11T05:41:58.266219Z","steps":["trace[1237082121] 'agreement among raft nodes before linearized reading' (duration: 177.043424ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:58.266387Z","caller":"traceutil/trace.go:171","msg":"trace[138215211] transaction","detail":"{read_only:false; response_revision:3145; number_of_response:1; }","duration":"178.423995ms","start":"2026-05-11T05:41:58.087947Z","end":"2026-05-11T05:41:58.266371Z","steps":["trace[138215211] 'process raft request' (duration: 177.938018ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:41:58.397651Z","caller":"traceutil/trace.go:171","msg":"trace[904090243] transaction","detail":"{read_only:false; response_revision:3146; number_of_response:1; }","duration":"173.338098ms","start":"2026-05-11T05:41:58.224294Z","end":"2026-05-11T05:41:58.397633Z","steps":["trace[904090243] 'process raft request' (duration: 110.541886ms)","trace[904090243] 'compare' (duration: 62.481217ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:41:58.397771Z","caller":"traceutil/trace.go:171","msg":"trace[518300344] linearizableReadLoop","detail":"{readStateIndex:3269; appliedIndex:3268; }","duration":"128.201053ms","start":"2026-05-11T05:41:58.269563Z","end":"2026-05-11T05:41:58.397764Z","steps":["trace[518300344] 'read index received' (duration: 65.25555ms)","trace[518300344] 'applied index is now lower than readState.Index' (duration: 62.944663ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:41:58.397961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.396237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7462"} {"level":"info","ts":"2026-05-11T05:41:58.397991Z","caller":"traceutil/trace.go:171","msg":"trace[983760152] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:3147; }","duration":"128.436407ms","start":"2026-05-11T05:41:58.269547Z","end":"2026-05-11T05:41:58.397983Z","steps":["trace[983760152] 'agreement among raft nodes before linearized reading' (duration: 128.336395ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:00.50432Z","caller":"traceutil/trace.go:171","msg":"trace[132393332] linearizableReadLoop","detail":"{readStateIndex:3280; appliedIndex:3279; }","duration":"234.458813ms","start":"2026-05-11T05:42:00.269838Z","end":"2026-05-11T05:42:00.504296Z","steps":["trace[132393332] 'read index received' (duration: 234.25449ms)","trace[132393332] 'applied index is now lower than readState.Index' (duration: 203.193µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:42:00.504475Z","caller":"traceutil/trace.go:171","msg":"trace[1192297587] transaction","detail":"{read_only:false; response_revision:3157; number_of_response:1; }","duration":"289.03649ms","start":"2026-05-11T05:42:00.215385Z","end":"2026-05-11T05:42:00.504422Z","steps":["trace[1192297587] 'process raft request' (duration: 288.760105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:00.504747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.867939ms","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-05-11T05:42:00.504831Z","caller":"traceutil/trace.go:171","msg":"trace[576515252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3157; }","duration":"235.029771ms","start":"2026-05-11T05:42:00.269779Z","end":"2026-05-11T05:42:00.504809Z","steps":["trace[576515252] 'agreement among raft nodes before linearized reading' (duration: 234.760997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:00.709272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.902115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:42:00.709767Z","caller":"traceutil/trace.go:171","msg":"trace[1950964555] transaction","detail":"{read_only:false; response_revision:3158; number_of_response:1; }","duration":"304.94245ms","start":"2026-05-11T05:42:00.404789Z","end":"2026-05-11T05:42:00.709731Z","steps":["trace[1950964555] 'process raft request' (duration: 194.484807ms)","trace[1950964555] 'compare' (duration: 109.55799ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:42:00.709894Z","caller":"traceutil/trace.go:171","msg":"trace[1844241535] transaction","detail":"{read_only:false; response_revision:3159; number_of_response:1; }","duration":"304.645846ms","start":"2026-05-11T05:42:00.405229Z","end":"2026-05-11T05:42:00.709875Z","steps":["trace[1844241535] 'process raft request' (duration: 304.387692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:00.709935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:00.404763Z","time spent":"305.081913ms","remote":"127.0.0.1:41804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:42:00.710008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:00.405197Z","time spent":"304.749748ms","remote":"127.0.0.1:41804","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-05-11T05:42:00.710008Z","caller":"traceutil/trace.go:171","msg":"trace[1217006300] linearizableReadLoop","detail":"{readStateIndex:3283; appliedIndex:3280; }","duration":"205.603516ms","start":"2026-05-11T05:42:00.504386Z","end":"2026-05-11T05:42:00.709989Z","steps":["trace[1217006300] 'read index received' (duration: 94.892528ms)","trace[1217006300] 'applied index is now lower than readState.Index' (duration: 110.708028ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:42:00.710017Z","caller":"traceutil/trace.go:171","msg":"trace[1880740666] transaction","detail":"{read_only:false; response_revision:3160; number_of_response:1; }","duration":"278.686654ms","start":"2026-05-11T05:42:00.4313Z","end":"2026-05-11T05:42:00.709987Z","steps":["trace[1880740666] 'process raft request' (duration: 278.401079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:00.710153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.04593ms","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-05-11T05:42:00.71018Z","caller":"traceutil/trace.go:171","msg":"trace[2016706950] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3160; }","duration":"293.102051ms","start":"2026-05-11T05:42:00.417072Z","end":"2026-05-11T05:42:00.710174Z","steps":["trace[2016706950] 'agreement among raft nodes before linearized reading' (duration: 293.01649ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:00.710529Z","caller":"traceutil/trace.go:171","msg":"trace[1448824868] transaction","detail":"{read_only:false; response_revision:3161; number_of_response:1; }","duration":"201.536484ms","start":"2026-05-11T05:42:00.508978Z","end":"2026-05-11T05:42:00.710514Z","steps":["trace[1448824868] 'process raft request' (duration: 200.830443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:00.710749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.405363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-11T05:42:00.710863Z","caller":"traceutil/trace.go:171","msg":"trace[1021700957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3161; }","duration":"174.562125ms","start":"2026-05-11T05:42:00.536283Z","end":"2026-05-11T05:42:00.710845Z","steps":["trace[1021700957] 'agreement among raft nodes before linearized reading' (duration: 174.153959ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:09.350342Z","caller":"traceutil/trace.go:171","msg":"trace[1992138628] linearizableReadLoop","detail":"{readStateIndex:3359; appliedIndex:3358; }","duration":"142.986773ms","start":"2026-05-11T05:42:09.207336Z","end":"2026-05-11T05:42:09.350322Z","steps":["trace[1992138628] 'read index received' (duration: 142.79387ms)","trace[1992138628] 'applied index is now lower than readState.Index' (duration: 192.003µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:42:09.350554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.207206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-11T05:42:09.350605Z","caller":"traceutil/trace.go:171","msg":"trace[932252130] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:3234; }","duration":"143.311878ms","start":"2026-05-11T05:42:09.207284Z","end":"2026-05-11T05:42:09.350596Z","steps":["trace[932252130] 'agreement among raft nodes before linearized reading' (duration: 143.122475ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:09.351023Z","caller":"traceutil/trace.go:171","msg":"trace[56362797] transaction","detail":"{read_only:false; response_revision:3234; number_of_response:1; }","duration":"209.313176ms","start":"2026-05-11T05:42:09.141674Z","end":"2026-05-11T05:42:09.350987Z","steps":["trace[56362797] 'process raft request' (duration: 208.542694ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:09.807195Z","caller":"traceutil/trace.go:171","msg":"trace[1761711274] transaction","detail":"{read_only:false; response_revision:3235; number_of_response:1; }","duration":"449.187895ms","start":"2026-05-11T05:42:09.357977Z","end":"2026-05-11T05:42:09.807165Z","steps":["trace[1761711274] 'process raft request' (duration: 424.806626ms)","trace[1761711274] 'compare' (duration: 24.005882ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:42:09.80731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:09.357966Z","time spent":"449.283626ms","remote":"127.0.0.1:41712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:42:09.841469Z","caller":"traceutil/trace.go:171","msg":"trace[1810399760] transaction","detail":"{read_only:false; response_revision:3237; number_of_response:1; }","duration":"482.748953ms","start":"2026-05-11T05:42:09.358684Z","end":"2026-05-11T05:42:09.841433Z","steps":["trace[1810399760] 'process raft request' (duration: 482.433308ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:09.841652Z","caller":"traceutil/trace.go:171","msg":"trace[679619526] transaction","detail":"{read_only:false; response_revision:3238; number_of_response:1; }","duration":"482.877174ms","start":"2026-05-11T05:42:09.35876Z","end":"2026-05-11T05:42:09.841637Z","steps":["trace[679619526] 'process raft request' (duration: 482.509498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:09.84179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:09.358664Z","time spent":"482.906575ms","remote":"127.0.0.1:41806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1429,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:42:09.841916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:09.358753Z","time spent":"482.975904ms","remote":"127.0.0.1:41712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":951,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:42:09.841559Z","caller":"traceutil/trace.go:171","msg":"trace[742639716] linearizableReadLoop","detail":"{readStateIndex:3363; appliedIndex:3359; }","duration":"301.861635ms","start":"2026-05-11T05:42:09.539684Z","end":"2026-05-11T05:42:09.841546Z","steps":["trace[742639716] 'read index received' (duration: 243.194388ms)","trace[742639716] 'applied index is now lower than readState.Index' (duration: 58.666187ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:42:09.842167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.492524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:42:09.842198Z","caller":"traceutil/trace.go:171","msg":"trace[2034452772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3238; }","duration":"302.567237ms","start":"2026-05-11T05:42:09.53962Z","end":"2026-05-11T05:42:09.842188Z","steps":["trace[2034452772] 'agreement among raft nodes before linearized reading' (duration: 302.493106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:09.842225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:09.539597Z","time spent":"302.623137ms","remote":"127.0.0.1:51574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T05:42:09.84121Z","caller":"traceutil/trace.go:171","msg":"trace[14648552] transaction","detail":"{read_only:false; response_revision:3236; number_of_response:1; }","duration":"482.554728ms","start":"2026-05-11T05:42:09.358631Z","end":"2026-05-11T05:42:09.841186Z","steps":["trace[14648552] 'process raft request' (duration: 482.321195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:09.84283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:42:09.358621Z","time spent":"484.079552ms","remote":"127.0.0.1:41806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:42:10.902125Z","caller":"traceutil/trace.go:171","msg":"trace[970121243] linearizableReadLoop","detail":"{readStateIndex:3368; appliedIndex:3366; }","duration":"135.169324ms","start":"2026-05-11T05:42:10.766886Z","end":"2026-05-11T05:42:10.902055Z","steps":["trace[970121243] 'read index received' (duration: 8.57323ms)","trace[970121243] 'applied index is now lower than readState.Index' (duration: 126.594714ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:42:10.902481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.581721ms","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-05-11T05:42:10.90255Z","caller":"traceutil/trace.go:171","msg":"trace[607750031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3243; }","duration":"135.688322ms","start":"2026-05-11T05:42:10.766845Z","end":"2026-05-11T05:42:10.902534Z","steps":["trace[607750031] 'agreement among raft nodes before linearized reading' (duration: 135.509939ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:10.90316Z","caller":"traceutil/trace.go:171","msg":"trace[2121701163] transaction","detail":"{read_only:false; response_revision:3243; number_of_response:1; }","duration":"139.53111ms","start":"2026-05-11T05:42:10.762702Z","end":"2026-05-11T05:42:10.902233Z","steps":["trace[2121701163] 'process raft request' (duration: 138.969962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:10.92374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.313018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-11T05:42:10.923833Z","caller":"traceutil/trace.go:171","msg":"trace[563031728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3244; }","duration":"145.449199ms","start":"2026-05-11T05:42:10.778362Z","end":"2026-05-11T05:42:10.923811Z","steps":["trace[563031728] 'agreement among raft nodes before linearized reading' (duration: 145.195586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:10.923838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.650142ms","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-05-11T05:42:10.923922Z","caller":"traceutil/trace.go:171","msg":"trace[523966167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3244; }","duration":"141.781504ms","start":"2026-05-11T05:42:10.782121Z","end":"2026-05-11T05:42:10.923903Z","steps":["trace[523966167] 'agreement among raft nodes before linearized reading' (duration: 141.51743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:35.07964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.441791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:42:35.07976Z","caller":"traceutil/trace.go:171","msg":"trace[1698376237] transaction","detail":"{read_only:false; response_revision:3476; number_of_response:1; }","duration":"236.179529ms","start":"2026-05-11T05:42:34.843562Z","end":"2026-05-11T05:42:35.079741Z","steps":["trace[1698376237] 'process raft request' (duration: 100.344492ms)","trace[1698376237] 'compare' (duration: 134.948454ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:42:35.098688Z","caller":"traceutil/trace.go:171","msg":"trace[751305697] transaction","detail":"{read_only:false; response_revision:3477; number_of_response:1; }","duration":"220.699096ms","start":"2026-05-11T05:42:34.87796Z","end":"2026-05-11T05:42:35.098659Z","steps":["trace[751305697] 'process raft request' (duration: 220.438652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:35.098938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.284508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T05:42:35.099176Z","caller":"traceutil/trace.go:171","msg":"trace[816397792] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:3477; }","duration":"204.657293ms","start":"2026-05-11T05:42:34.894492Z","end":"2026-05-11T05:42:35.09915Z","steps":["trace[816397792] 'agreement among raft nodes before linearized reading' (duration: 204.273918ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:35.098619Z","caller":"traceutil/trace.go:171","msg":"trace[1989423043] linearizableReadLoop","detail":"{readStateIndex:3609; appliedIndex:3607; }","duration":"204.046165ms","start":"2026-05-11T05:42:34.894535Z","end":"2026-05-11T05:42:35.098581Z","steps":["trace[1989423043] 'read index received' (duration: 49.357663ms)","trace[1989423043] 'applied index is now lower than readState.Index' (duration: 154.687112ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:42:35.13475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.183069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:42:35.134835Z","caller":"traceutil/trace.go:171","msg":"trace[506935082] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:3479; }","duration":"100.335772ms","start":"2026-05-11T05:42:35.034477Z","end":"2026-05-11T05:42:35.134813Z","steps":["trace[506935082] 'agreement among raft nodes before linearized reading' (duration: 100.18295ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:35.135125Z","caller":"traceutil/trace.go:171","msg":"trace[932255765] transaction","detail":"{read_only:false; response_revision:3478; number_of_response:1; }","duration":"138.05241ms","start":"2026-05-11T05:42:34.997061Z","end":"2026-05-11T05:42:35.135113Z","steps":["trace[932255765] 'process raft request' (duration: 137.434881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:42:46.180527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.567045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-05-11T05:42:46.180527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.719516ms","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-05-11T05:42:46.180618Z","caller":"traceutil/trace.go:171","msg":"trace[1952972766] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3545; }","duration":"148.712048ms","start":"2026-05-11T05:42:46.031888Z","end":"2026-05-11T05:42:46.1806Z","steps":["trace[1952972766] 'range keys from in-memory index tree' (duration: 148.358153ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:46.180673Z","caller":"traceutil/trace.go:171","msg":"trace[863925824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3545; }","duration":"102.868168ms","start":"2026-05-11T05:42:46.077742Z","end":"2026-05-11T05:42:46.180611Z","steps":["trace[863925824] 'range keys from in-memory index tree' (duration: 102.524063ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:51.519497Z","caller":"traceutil/trace.go:171","msg":"trace[1421558117] transaction","detail":"{read_only:false; response_revision:3597; number_of_response:1; }","duration":"121.961415ms","start":"2026-05-11T05:42:51.39751Z","end":"2026-05-11T05:42:51.519471Z","steps":["trace[1421558117] 'process raft request' (duration: 121.771082ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:51.519779Z","caller":"traceutil/trace.go:171","msg":"trace[1901744725] transaction","detail":"{read_only:false; response_revision:3599; number_of_response:1; }","duration":"121.876753ms","start":"2026-05-11T05:42:51.397882Z","end":"2026-05-11T05:42:51.519759Z","steps":["trace[1901744725] 'process raft request' (duration: 121.763121ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:51.519875Z","caller":"traceutil/trace.go:171","msg":"trace[26487114] transaction","detail":"{read_only:false; response_revision:3598; number_of_response:1; }","duration":"122.030896ms","start":"2026-05-11T05:42:51.397762Z","end":"2026-05-11T05:42:51.519792Z","steps":["trace[26487114] 'process raft request' (duration: 121.67059ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:42:51.519977Z","caller":"traceutil/trace.go:171","msg":"trace[1568007362] transaction","detail":"{read_only:false; response_revision:3600; number_of_response:1; }","duration":"116.092797ms","start":"2026-05-11T05:42:51.403846Z","end":"2026-05-11T05:42:51.519939Z","steps":["trace[1568007362] 'process raft request' (duration: 115.884213ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:46:23.849414Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2849} {"level":"info","ts":"2026-05-11T05:46:23.935724Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2849,"took":"85.190734ms","hash":2767983334,"current-db-size-bytes":37896192,"current-db-size":"38 MB","current-db-size-in-use-bytes":13750272,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-05-11T05:46:23.93579Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2767983334,"revision":2849,"compact-revision":-1} {"level":"info","ts":"2026-05-11T05:50:29.284209Z","caller":"traceutil/trace.go:171","msg":"trace[2108163877] transaction","detail":"{read_only:false; response_revision:7028; number_of_response:1; }","duration":"104.499573ms","start":"2026-05-11T05:50:29.179683Z","end":"2026-05-11T05:50:29.284182Z","steps":["trace[2108163877] 'process raft request' (duration: 62.613176ms)","trace[2108163877] 'compare' (duration: 41.699694ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:50:29.287816Z","caller":"traceutil/trace.go:171","msg":"trace[2141782467] transaction","detail":"{read_only:false; response_revision:7029; number_of_response:1; }","duration":"103.509877ms","start":"2026-05-11T05:50:29.184287Z","end":"2026-05-11T05:50:29.287797Z","steps":["trace[2141782467] 'process raft request' (duration: 103.089421ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:50:31.163455Z","caller":"traceutil/trace.go:171","msg":"trace[17861577] transaction","detail":"{read_only:false; response_revision:7106; number_of_response:1; }","duration":"130.110175ms","start":"2026-05-11T05:50:31.033293Z","end":"2026-05-11T05:50:31.163403Z","steps":["trace[17861577] 'process raft request' (duration: 85.006642ms)","trace[17861577] 'compare' (duration: 44.949722ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:50:31.170682Z","caller":"traceutil/trace.go:171","msg":"trace[48545871] transaction","detail":"{read_only:false; response_revision:7107; number_of_response:1; }","duration":"133.877052ms","start":"2026-05-11T05:50:31.036771Z","end":"2026-05-11T05:50:31.170648Z","steps":["trace[48545871] 'process raft request' (duration: 133.653959ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:50:31.170539Z","caller":"traceutil/trace.go:171","msg":"trace[1656900905] linearizableReadLoop","detail":"{readStateIndex:7373; appliedIndex:7371; }","duration":"125.267414ms","start":"2026-05-11T05:50:31.045258Z","end":"2026-05-11T05:50:31.170525Z","steps":["trace[1656900905] 'read index received' (duration: 73.059443ms)","trace[1656900905] 'applied index is now lower than readState.Index' (duration: 52.207221ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:50:31.170988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.707461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-11ab713a-fe88-4183-9d14-957e708b2d58\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-05-11T05:50:31.171132Z","caller":"traceutil/trace.go:171","msg":"trace[485365305] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-11ab713a-fe88-4183-9d14-957e708b2d58; range_end:; response_count:1; response_revision:7107; }","duration":"125.877573ms","start":"2026-05-11T05:50:31.045239Z","end":"2026-05-11T05:50:31.171116Z","steps":["trace[485365305] 'agreement among raft nodes before linearized reading' (duration: 125.459636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:50:31.193897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.966609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2195922"} {"level":"info","ts":"2026-05-11T05:50:31.19398Z","caller":"traceutil/trace.go:171","msg":"trace[714554959] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:7108; }","duration":"113.061101ms","start":"2026-05-11T05:50:31.080902Z","end":"2026-05-11T05:50:31.193963Z","steps":["trace[714554959] 'agreement among raft nodes before linearized reading' (duration: 112.284649ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:50:33.397991Z","caller":"traceutil/trace.go:171","msg":"trace[628862125] transaction","detail":"{read_only:false; response_revision:7128; number_of_response:1; }","duration":"103.271344ms","start":"2026-05-11T05:50:33.294689Z","end":"2026-05-11T05:50:33.397961Z","steps":["trace[628862125] 'process raft request' (duration: 50.20838ms)","trace[628862125] 'compare' (duration: 52.945723ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:50:33.438105Z","caller":"traceutil/trace.go:171","msg":"trace[2069530297] transaction","detail":"{read_only:false; response_revision:7130; number_of_response:1; }","duration":"136.645823ms","start":"2026-05-11T05:50:33.301439Z","end":"2026-05-11T05:50:33.438085Z","steps":["trace[2069530297] 'process raft request' (duration: 136.619153ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:50:33.438152Z","caller":"traceutil/trace.go:171","msg":"trace[1843751233] transaction","detail":"{read_only:false; response_revision:7129; number_of_response:1; }","duration":"138.927049ms","start":"2026-05-11T05:50:33.299191Z","end":"2026-05-11T05:50:33.438118Z","steps":["trace[1843751233] 'process raft request' (duration: 138.771086ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:50:50.069029Z","caller":"traceutil/trace.go:171","msg":"trace[776707301] transaction","detail":"{read_only:false; response_revision:7347; number_of_response:1; }","duration":"150.727614ms","start":"2026-05-11T05:50:49.918279Z","end":"2026-05-11T05:50:50.069007Z","steps":["trace[776707301] 'process raft request' (duration: 73.125983ms)","trace[776707301] 'compare' (duration: 77.492059ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:50:50.27979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.195537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-11T05:50:50.279871Z","caller":"traceutil/trace.go:171","msg":"trace[352234518] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7348; }","duration":"106.337509ms","start":"2026-05-11T05:50:50.173516Z","end":"2026-05-11T05:50:50.279853Z","steps":["trace[352234518] 'range keys from in-memory index tree' (duration: 106.017435ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:51:23.855612Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4865} {"level":"info","ts":"2026-05-11T05:51:23.907435Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4865,"took":"50.81079ms","hash":3103023605,"current-db-size-bytes":37896192,"current-db-size":"38 MB","current-db-size-in-use-bytes":28200960,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-11T05:51:23.907489Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3103023605,"revision":4865,"compact-revision":2849}