{"level":"warn","ts":"2026-08-10T22:25:43.516329Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-10T22:25:43.51643Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.238: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.19.213.238:2380","--initial-cluster=instance=https://199.19.213.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.238: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-08-10T22:25:43.516618Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-10T22:25:43.51664Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.238:2380"]} {"level":"info","ts":"2026-08-10T22:25:43.516716Z","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-08-10T22:25:43.517389Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.238:2379"]} {"level":"info","ts":"2026-08-10T22:25:43.517571Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.238:2380"],"listen-peer-urls":["https://199.19.213.238:2380"],"advertise-client-urls":["https://199.19.213.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.238: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-08-10T22:25:43.519789Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.915123ms"} {"level":"info","ts":"2026-08-10T22:25:43.523928Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8cf6035374726417","cluster-id":"e837e6e114b087e2"} {"level":"info","ts":"2026-08-10T22:25:43.523987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 switched to configuration voters=()"} {"level":"info","ts":"2026-08-10T22:25:43.524004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became follower at term 0"} {"level":"info","ts":"2026-08-10T22:25:43.524017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8cf6035374726417 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-10T22:25:43.524026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became follower at term 1"} {"level":"info","ts":"2026-08-10T22:25:43.524082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 switched to configuration voters=(10157309666551555095)"} {"level":"warn","ts":"2026-08-10T22:25:43.529135Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-10T22:25:43.531295Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-10T22:25:43.532348Z","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-08-10T22:25:43.53531Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8cf6035374726417","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-10T22:25:43.535564Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8cf6035374726417","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-10T22:25:43.53562Z","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-08-10T22:25:43.535687Z","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-08-10T22:25:43.535758Z","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-08-10T22:25:43.537158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 switched to configuration voters=(10157309666551555095)"} {"level":"info","ts":"2026-08-10T22:25:43.537652Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e837e6e114b087e2","local-member-id":"8cf6035374726417","added-peer-id":"8cf6035374726417","added-peer-peer-urls":["https://199.19.213.238:2380"]} {"level":"info","ts":"2026-08-10T22:25:43.540316Z","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-08-10T22:25:43.540436Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.238:2380"} {"level":"info","ts":"2026-08-10T22:25:43.540489Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.238:2380"} {"level":"info","ts":"2026-08-10T22:25:43.540646Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8cf6035374726417","initial-advertise-peer-urls":["https://199.19.213.238:2380"],"listen-peer-urls":["https://199.19.213.238:2380"],"advertise-client-urls":["https://199.19.213.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-10T22:25:43.540699Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-10T22:25:44.025324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 is starting a new election at term 1"} {"level":"info","ts":"2026-08-10T22:25:44.025396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-10T22:25:44.025438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 received MsgPreVoteResp from 8cf6035374726417 at term 1"} {"level":"info","ts":"2026-08-10T22:25:44.025462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became candidate at term 2"} {"level":"info","ts":"2026-08-10T22:25:44.025478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 received MsgVoteResp from 8cf6035374726417 at term 2"} {"level":"info","ts":"2026-08-10T22:25:44.025498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became leader at term 2"} {"level":"info","ts":"2026-08-10T22:25:44.025515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8cf6035374726417 elected leader 8cf6035374726417 at term 2"} {"level":"info","ts":"2026-08-10T22:25:44.026444Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:25:44.027292Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8cf6035374726417","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.238:2379]}","request-path":"/0/members/8cf6035374726417/attributes","cluster-id":"e837e6e114b087e2","publish-timeout":"7s"} {"level":"info","ts":"2026-08-10T22:25:44.027383Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T22:25:44.027416Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T22:25:44.027626Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-10T22:25:44.027644Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-10T22:25:44.030194Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e837e6e114b087e2","local-member-id":"8cf6035374726417","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:25:44.030389Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:25:44.030446Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:25:44.031172Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-10T22:25:44.03146Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.238:2379"} {"level":"warn","ts":"2026-08-10T22:26:02.406923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.453571ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-10T22:26:26.789522Z","caller":"traceutil/trace.go:171","msg":"trace[1631767741] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"119.579194ms","start":"2026-08-10T22:26:26.669913Z","end":"2026-08-10T22:26:26.789493Z","steps":["trace[1631767741] 'read index received' (duration: 119.41287ms)","trace[1631767741] 'applied index is now lower than readState.Index' (duration: 165.044µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:26:26.789628Z","caller":"traceutil/trace.go:171","msg":"trace[408518201] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"215.099687ms","start":"2026-08-10T22:26:26.574492Z","end":"2026-08-10T22:26:26.789592Z","steps":["trace[408518201] 'process raft request' (duration: 214.84221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:26.79001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.063475ms","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-08-10T22:26:26.790156Z","caller":"traceutil/trace.go:171","msg":"trace[1983383975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"120.26313ms","start":"2026-08-10T22:26:26.66987Z","end":"2026-08-10T22:26:26.790133Z","steps":["trace[1983383975] 'agreement among raft nodes before linearized reading' (duration: 119.774329ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:27.13066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.035689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:26:27.130794Z","caller":"traceutil/trace.go:171","msg":"trace[98534648] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"330.936148ms","start":"2026-08-10T22:26:26.799842Z","end":"2026-08-10T22:26:27.130778Z","steps":["trace[98534648] 'read index received' (duration: 108.037709ms)","trace[98534648] 'applied index is now lower than readState.Index' (duration: 222.897199ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:26:27.130845Z","caller":"traceutil/trace.go:171","msg":"trace[1916707196] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"335.233163ms","start":"2026-08-10T22:26:26.795578Z","end":"2026-08-10T22:26:27.130811Z","steps":["trace[1916707196] 'process raft request' (duration: 112.318044ms)","trace[1916707196] 'compare' (duration: 221.897376ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:26:27.130902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.06389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:26:27.130935Z","caller":"traceutil/trace.go:171","msg":"trace[1105366588] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:591; }","duration":"331.124372ms","start":"2026-08-10T22:26:26.799799Z","end":"2026-08-10T22:26:27.130923Z","steps":["trace[1105366588] 'agreement among raft nodes before linearized reading' (duration: 331.073251ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:27.130971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:26.79978Z","time spent":"331.182243ms","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-08-10T22:26:27.131051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:26.795562Z","time spent":"335.361525ms","remote":"127.0.0.1:38028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:26:27.131054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.596039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T22:26:27.131072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.640208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-10T22:26:27.131152Z","caller":"traceutil/trace.go:171","msg":"trace[1044813084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"159.726922ms","start":"2026-08-10T22:26:26.971387Z","end":"2026-08-10T22:26:27.131113Z","steps":["trace[1044813084] 'agreement among raft nodes before linearized reading' (duration: 159.510377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:27.13116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.572046ms","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-08-10T22:26:27.131168Z","caller":"traceutil/trace.go:171","msg":"trace[2006353915] range","detail":"{range_begin:/registry/masterleases/199.19.213.238; range_end:; response_count:1; response_revision:591; }","duration":"116.768952ms","start":"2026-08-10T22:26:27.014378Z","end":"2026-08-10T22:26:27.131147Z","steps":["trace[2006353915] 'agreement among raft nodes before linearized reading' (duration: 116.532977ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:27.1312Z","caller":"traceutil/trace.go:171","msg":"trace[1202321773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"139.649427ms","start":"2026-08-10T22:26:26.99154Z","end":"2026-08-10T22:26:27.13119Z","steps":["trace[1202321773] 'agreement among raft nodes before linearized reading' (duration: 139.390951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:27.58225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.278314ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-08-10T22:26:27.582481Z","caller":"traceutil/trace.go:171","msg":"trace[1352459280] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"438.444326ms","start":"2026-08-10T22:26:27.144019Z","end":"2026-08-10T22:26:27.582463Z","steps":["trace[1352459280] 'process raft request' (duration: 438.393264ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:27.582541Z","caller":"traceutil/trace.go:171","msg":"trace[834212142] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"448.085699ms","start":"2026-08-10T22:26:27.134433Z","end":"2026-08-10T22:26:27.582519Z","steps":["trace[834212142] 'process raft request' (duration: 447.895334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:27.582575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:27.143994Z","time spent":"438.528948ms","remote":"127.0.0.1:37932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:26:27.582545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:27.132015Z","time spent":"450.522702ms","remote":"127.0.0.1:37780","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-10T22:26:27.582673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:27.134417Z","time spent":"448.198771ms","remote":"127.0.0.1:38028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:27:08.441995Z","caller":"traceutil/trace.go:171","msg":"trace[870998276] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"132.97887ms","start":"2026-08-10T22:27:08.308995Z","end":"2026-08-10T22:27:08.441974Z","steps":["trace[870998276] 'process raft request' (duration: 132.841807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:27:52.241755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.094686ms","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-08-10T22:27:52.241835Z","caller":"traceutil/trace.go:171","msg":"trace[1030494608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1790; }","duration":"192.196319ms","start":"2026-08-10T22:27:52.049623Z","end":"2026-08-10T22:27:52.241819Z","steps":["trace[1030494608] 'range keys from in-memory index tree' (duration: 191.961593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:27:52.242419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.071324ms","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-08-10T22:27:52.242514Z","caller":"traceutil/trace.go:171","msg":"trace[1937005022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1790; }","duration":"200.637256ms","start":"2026-08-10T22:27:52.041856Z","end":"2026-08-10T22:27:52.242493Z","steps":["trace[1937005022] 'range keys from in-memory index tree' (duration: 199.825588ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:27:52.311359Z","caller":"traceutil/trace.go:171","msg":"trace[244929089] linearizableReadLoop","detail":"{readStateIndex:1856; appliedIndex:1855; }","duration":"106.428689ms","start":"2026-08-10T22:27:52.2049Z","end":"2026-08-10T22:27:52.311329Z","steps":["trace[244929089] 'read index received' (duration: 64.088687ms)","trace[244929089] 'applied index is now lower than readState.Index' (duration: 42.338582ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:27:52.311589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.661704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-10T22:27:52.311628Z","caller":"traceutil/trace.go:171","msg":"trace[1563549090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1790; }","duration":"106.753897ms","start":"2026-08-10T22:27:52.204864Z","end":"2026-08-10T22:27:52.311618Z","steps":["trace[1563549090] 'agreement among raft nodes before linearized reading' (duration: 106.573243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:27:52.604879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.363404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0089ea83-b17f-4fa3-8aca-6381a549d1c7\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-10T22:27:52.604951Z","caller":"traceutil/trace.go:171","msg":"trace[947220535] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0089ea83-b17f-4fa3-8aca-6381a549d1c7; range_end:; response_count:1; response_revision:1793; }","duration":"100.494667ms","start":"2026-08-10T22:27:52.504441Z","end":"2026-08-10T22:27:52.604935Z","steps":["trace[947220535] 'range keys from in-memory index tree' (duration: 100.18028ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:32.193153Z","caller":"traceutil/trace.go:171","msg":"trace[1666971615] linearizableReadLoop","detail":"{readStateIndex:2139; appliedIndex:2138; }","duration":"222.356158ms","start":"2026-08-10T22:28:31.970771Z","end":"2026-08-10T22:28:32.193127Z","steps":["trace[1666971615] 'read index received' (duration: 222.278877ms)","trace[1666971615] 'applied index is now lower than readState.Index' (duration: 75.431µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:28:32.193296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.510502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:28:32.193367Z","caller":"traceutil/trace.go:171","msg":"trace[1002333063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2065; }","duration":"222.625205ms","start":"2026-08-10T22:28:31.970732Z","end":"2026-08-10T22:28:32.193357Z","steps":["trace[1002333063] 'agreement among raft nodes before linearized reading' (duration: 222.514972ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:32.193661Z","caller":"traceutil/trace.go:171","msg":"trace[1947635819] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"231.40838ms","start":"2026-08-10T22:28:31.961867Z","end":"2026-08-10T22:28:32.193275Z","steps":["trace[1947635819] 'process raft request' (duration: 231.109143ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:32.299542Z","caller":"traceutil/trace.go:171","msg":"trace[163017927] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"317.934773ms","start":"2026-08-10T22:28:31.981575Z","end":"2026-08-10T22:28:32.29951Z","steps":["trace[163017927] 'process raft request' (duration: 274.238446ms)","trace[163017927] 'compare' (duration: 43.482421ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:28:32.299621Z","caller":"traceutil/trace.go:171","msg":"trace[2144059286] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"141.565103ms","start":"2026-08-10T22:28:32.158034Z","end":"2026-08-10T22:28:32.299599Z","steps":["trace[2144059286] 'process raft request' (duration: 141.395009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:32.299709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:31.98155Z","time spent":"318.081977ms","remote":"127.0.0.1:38028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:28:32.299851Z","caller":"traceutil/trace.go:171","msg":"trace[1519325375] linearizableReadLoop","detail":"{readStateIndex:2141; appliedIndex:2139; }","duration":"105.233402ms","start":"2026-08-10T22:28:32.194447Z","end":"2026-08-10T22:28:32.299681Z","steps":["trace[1519325375] 'read index received' (duration: 61.362871ms)","trace[1519325375] 'applied index is now lower than readState.Index' (duration: 43.868511ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:28:32.339622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.142243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:28:32.339724Z","caller":"traceutil/trace.go:171","msg":"trace[1198755843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2067; }","duration":"145.260305ms","start":"2026-08-10T22:28:32.194439Z","end":"2026-08-10T22:28:32.3397Z","steps":["trace[1198755843] 'agreement among raft nodes before linearized reading' (duration: 105.358114ms)","trace[1198755843] 'range keys from in-memory index tree' (duration: 39.734317ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:28:38.243853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.797932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:28:38.243943Z","caller":"traceutil/trace.go:171","msg":"trace[330257983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2096; }","duration":"271.951295ms","start":"2026-08-10T22:28:37.971973Z","end":"2026-08-10T22:28:38.243924Z","steps":["trace[330257983] 'range keys from in-memory index tree' (duration: 271.699579ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:38.243864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.008282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-08-10T22:28:38.244094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.376537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T22:28:38.244159Z","caller":"traceutil/trace.go:171","msg":"trace[1597216915] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2096; }","duration":"324.482109ms","start":"2026-08-10T22:28:37.919663Z","end":"2026-08-10T22:28:38.244145Z","steps":["trace[1597216915] 'count revisions from in-memory index tree' (duration: 324.296246ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:38.244108Z","caller":"traceutil/trace.go:171","msg":"trace[118557785] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2096; }","duration":"322.27709ms","start":"2026-08-10T22:28:37.921798Z","end":"2026-08-10T22:28:38.244075Z","steps":["trace[118557785] 'range keys from in-memory index tree' (duration: 321.835629ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:38.244294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:37.921785Z","time spent":"322.492434ms","remote":"127.0.0.1:51030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-10T22:28:38.244217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:37.919644Z","time spent":"324.55571ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":2,"response size":31,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-08-10T22:28:43.606531Z","caller":"traceutil/trace.go:171","msg":"trace[1089863306] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"219.554135ms","start":"2026-08-10T22:28:43.38693Z","end":"2026-08-10T22:28:43.606484Z","steps":["trace[1089863306] 'process raft request' (duration: 219.255768ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:29:32.46423Z","caller":"traceutil/trace.go:171","msg":"trace[385118318] linearizableReadLoop","detail":"{readStateIndex:2658; appliedIndex:2657; }","duration":"115.630563ms","start":"2026-08-10T22:29:32.348566Z","end":"2026-08-10T22:29:32.464197Z","steps":["trace[385118318] 'read index received' (duration: 61.164942ms)","trace[385118318] 'applied index is now lower than readState.Index' (duration: 54.463431ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:29:32.464504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.92659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-10T22:29:32.464594Z","caller":"traceutil/trace.go:171","msg":"trace[1008895394] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2562; }","duration":"116.058594ms","start":"2026-08-10T22:29:32.348512Z","end":"2026-08-10T22:29:32.464571Z","steps":["trace[1008895394] 'agreement among raft nodes before linearized reading' (duration: 115.808317ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:30:06.065357Z","caller":"traceutil/trace.go:171","msg":"trace[2057106618] transaction","detail":"{read_only:false; response_revision:2765; number_of_response:1; }","duration":"135.656708ms","start":"2026-08-10T22:30:05.929674Z","end":"2026-08-10T22:30:06.065331Z","steps":["trace[2057106618] 'process raft request' (duration: 135.31695ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:59.622075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.803012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:33:59.62219Z","caller":"traceutil/trace.go:171","msg":"trace[26998033] linearizableReadLoop","detail":"{readStateIndex:5098; appliedIndex:5097; }","duration":"289.444258ms","start":"2026-08-10T22:33:59.332728Z","end":"2026-08-10T22:33:59.622172Z","steps":["trace[26998033] 'read index received' (duration: 146.552554ms)","trace[26998033] 'applied index is now lower than readState.Index' (duration: 142.890374ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:33:59.622266Z","caller":"traceutil/trace.go:171","msg":"trace[1735452283] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"289.550461ms","start":"2026-08-10T22:33:59.332701Z","end":"2026-08-10T22:33:59.622252Z","steps":["trace[1735452283] 'process raft request' (duration: 146.483243ms)","trace[1735452283] 'compare' (duration: 142.6715ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:33:59.622409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.657543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-r9zbd.18ca9275e76d6e0b\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-08-10T22:33:59.622479Z","caller":"traceutil/trace.go:171","msg":"trace[1390478111] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-r9zbd.18ca9275e76d6e0b; range_end:; response_count:1; response_revision:4913; }","duration":"289.749425ms","start":"2026-08-10T22:33:59.332711Z","end":"2026-08-10T22:33:59.622461Z","steps":["trace[1390478111] 'agreement among raft nodes before linearized reading' (duration: 289.568371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:59.623921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.954002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-10T22:33:59.623992Z","caller":"traceutil/trace.go:171","msg":"trace[375729125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4914; }","duration":"236.062684ms","start":"2026-08-10T22:33:59.387914Z","end":"2026-08-10T22:33:59.623976Z","steps":["trace[375729125] 'agreement among raft nodes before linearized reading' (duration: 235.869469ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:33:59.624035Z","caller":"traceutil/trace.go:171","msg":"trace[1673889185] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"216.416622ms","start":"2026-08-10T22:33:59.407571Z","end":"2026-08-10T22:33:59.623987Z","steps":["trace[1673889185] 'process raft request' (duration: 216.100455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:59.624219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.277767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-10T22:33:59.624249Z","caller":"traceutil/trace.go:171","msg":"trace[1263983170] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4914; }","duration":"180.306317ms","start":"2026-08-10T22:33:59.443934Z","end":"2026-08-10T22:33:59.62424Z","steps":["trace[1263983170] 'agreement among raft nodes before linearized reading' (duration: 180.233946ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:33:59.624514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.606102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-10T22:33:59.624608Z","caller":"traceutil/trace.go:171","msg":"trace[1421167883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4914; }","duration":"204.743625ms","start":"2026-08-10T22:33:59.419843Z","end":"2026-08-10T22:33:59.624587Z","steps":["trace[1421167883] 'agreement among raft nodes before linearized reading' (duration: 204.414948ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:21.802506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.962844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:34:21.802664Z","caller":"traceutil/trace.go:171","msg":"trace[1079613783] transaction","detail":"{read_only:false; response_revision:5355; number_of_response:1; }","duration":"184.945319ms","start":"2026-08-10T22:34:21.617696Z","end":"2026-08-10T22:34:21.802641Z","steps":["trace[1079613783] 'process raft request' (duration: 72.74737ms)","trace[1079613783] 'compare' (duration: 111.840191ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:21.807043Z","caller":"traceutil/trace.go:171","msg":"trace[1644185779] transaction","detail":"{read_only:false; response_revision:5356; number_of_response:1; }","duration":"188.381526ms","start":"2026-08-10T22:34:21.618634Z","end":"2026-08-10T22:34:21.807016Z","steps":["trace[1644185779] 'process raft request' (duration: 188.18866ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:21.8073Z","caller":"traceutil/trace.go:171","msg":"trace[702650711] transaction","detail":"{read_only:false; response_revision:5357; number_of_response:1; }","duration":"187.857033ms","start":"2026-08-10T22:34:21.61941Z","end":"2026-08-10T22:34:21.807267Z","steps":["trace[702650711] 'process raft request' (duration: 187.545305ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:21.807371Z","caller":"traceutil/trace.go:171","msg":"trace[2002014205] transaction","detail":"{read_only:false; response_revision:5358; number_of_response:1; }","duration":"187.523266ms","start":"2026-08-10T22:34:21.61982Z","end":"2026-08-10T22:34:21.807344Z","steps":["trace[2002014205] 'process raft request' (duration: 187.380153ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:21.807593Z","caller":"traceutil/trace.go:171","msg":"trace[1534965088] transaction","detail":"{read_only:false; response_revision:5359; number_of_response:1; }","duration":"187.625238ms","start":"2026-08-10T22:34:21.619959Z","end":"2026-08-10T22:34:21.807585Z","steps":["trace[1534965088] 'process raft request' (duration: 187.334232ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:21.807691Z","caller":"traceutil/trace.go:171","msg":"trace[247848979] transaction","detail":"{read_only:false; response_revision:5360; number_of_response:1; }","duration":"187.287841ms","start":"2026-08-10T22:34:21.620389Z","end":"2026-08-10T22:34:21.807677Z","steps":["trace[247848979] 'process raft request' (duration: 187.104367ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:21.807874Z","caller":"traceutil/trace.go:171","msg":"trace[1461063736] transaction","detail":"{read_only:false; response_revision:5361; number_of_response:1; }","duration":"185.256326ms","start":"2026-08-10T22:34:21.622592Z","end":"2026-08-10T22:34:21.807848Z","steps":["trace[1461063736] 'process raft request' (duration: 185.020481ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:32.618107Z","caller":"traceutil/trace.go:171","msg":"trace[1569954088] linearizableReadLoop","detail":"{readStateIndex:5716; appliedIndex:5714; }","duration":"103.081452ms","start":"2026-08-10T22:34:32.515003Z","end":"2026-08-10T22:34:32.618085Z","steps":["trace[1569954088] 'read index received' (duration: 102.869117ms)","trace[1569954088] 'applied index is now lower than readState.Index' (duration: 211.295µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:34:32.618335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.316867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus.18ca92739c66485b\" ","response":"range_response_count:1 size:745"} {"level":"info","ts":"2026-08-10T22:34:32.618357Z","caller":"traceutil/trace.go:171","msg":"trace[1253934118] transaction","detail":"{read_only:false; response_revision:5514; number_of_response:1; }","duration":"120.773949ms","start":"2026-08-10T22:34:32.497564Z","end":"2026-08-10T22:34:32.618338Z","steps":["trace[1253934118] 'process raft request' (duration: 120.473333ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:32.618405Z","caller":"traceutil/trace.go:171","msg":"trace[1112869322] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus.18ca92739c66485b; range_end:; response_count:1; response_revision:5514; }","duration":"103.438989ms","start":"2026-08-10T22:34:32.514944Z","end":"2026-08-10T22:34:32.618383Z","steps":["trace[1112869322] 'agreement among raft nodes before linearized reading' (duration: 103.233535ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:32.6188Z","caller":"traceutil/trace.go:171","msg":"trace[1220529764] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"121.793932ms","start":"2026-08-10T22:34:32.496438Z","end":"2026-08-10T22:34:32.618231Z","steps":["trace[1220529764] 'process raft request' (duration: 121.465485ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:35:44.050349Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2972} {"level":"info","ts":"2026-08-10T22:35:44.122851Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2972,"took":"71.377376ms","hash":2500609473,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":34181120,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-10T22:35:44.122935Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2500609473,"revision":2972,"compact-revision":-1} {"level":"warn","ts":"2026-08-10T22:40:13.76766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.443059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-10T22:40:13.767753Z","caller":"traceutil/trace.go:171","msg":"trace[176172144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8111; }","duration":"129.589873ms","start":"2026-08-10T22:40:13.638144Z","end":"2026-08-10T22:40:13.767734Z","steps":["trace[176172144] 'range keys from in-memory index tree' (duration: 129.253016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:13.767731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.627984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-10T22:40:13.767818Z","caller":"traceutil/trace.go:171","msg":"trace[1414792396] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8111; }","duration":"119.769947ms","start":"2026-08-10T22:40:13.648029Z","end":"2026-08-10T22:40:13.767799Z","steps":["trace[1414792396] 'range keys from in-memory index tree' (duration: 119.365119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:13.767979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.459447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-10T22:40:13.768073Z","caller":"traceutil/trace.go:171","msg":"trace[1413333682] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8111; }","duration":"112.61053ms","start":"2026-08-10T22:40:13.655447Z","end":"2026-08-10T22:40:13.768058Z","steps":["trace[1413333682] 'range keys from in-memory index tree' (duration: 111.945816ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:25.445517Z","caller":"traceutil/trace.go:171","msg":"trace[130632414] linearizableReadLoop","detail":"{readStateIndex:8457; appliedIndex:8456; }","duration":"190.691584ms","start":"2026-08-10T22:40:25.254795Z","end":"2026-08-10T22:40:25.445487Z","steps":["trace[130632414] 'read index received' (duration: 190.548121ms)","trace[130632414] 'applied index is now lower than readState.Index' (duration: 141.963µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:40:25.445663Z","caller":"traceutil/trace.go:171","msg":"trace[66750893] transaction","detail":"{read_only:false; response_revision:8171; number_of_response:1; }","duration":"357.33302ms","start":"2026-08-10T22:40:25.088299Z","end":"2026-08-10T22:40:25.445632Z","steps":["trace[66750893] 'process raft request' (duration: 357.015933ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:25.445801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:40:25.088276Z","time spent":"357.414702ms","remote":"127.0.0.1:38028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:40:25.445778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.96422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:40:25.445867Z","caller":"traceutil/trace.go:171","msg":"trace[776254897] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8171; }","duration":"191.090223ms","start":"2026-08-10T22:40:25.254762Z","end":"2026-08-10T22:40:25.445853Z","steps":["trace[776254897] 'agreement among raft nodes before linearized reading' (duration: 190.900049ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:25.467786Z","caller":"traceutil/trace.go:171","msg":"trace[279618582] transaction","detail":"{read_only:false; response_revision:8172; number_of_response:1; }","duration":"124.545483ms","start":"2026-08-10T22:40:25.343225Z","end":"2026-08-10T22:40:25.467771Z","steps":["trace[279618582] 'process raft request' (duration: 124.449851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:25.467864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.244665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:40:25.467923Z","caller":"traceutil/trace.go:171","msg":"trace[663198845] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8172; }","duration":"124.320557ms","start":"2026-08-10T22:40:25.343586Z","end":"2026-08-10T22:40:25.467906Z","steps":["trace[663198845] 'agreement among raft nodes before linearized reading' (duration: 124.207944ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:25.467977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.360538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T22:40:25.468133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.488953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T22:40:25.468152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.350192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:40:25.468181Z","caller":"traceutil/trace.go:171","msg":"trace[964599257] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8172; }","duration":"177.554395ms","start":"2026-08-10T22:40:25.290614Z","end":"2026-08-10T22:40:25.468169Z","steps":["trace[964599257] 'agreement among raft nodes before linearized reading' (duration: 177.479734ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:25.468185Z","caller":"traceutil/trace.go:171","msg":"trace[1717756091] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8172; }","duration":"100.404073ms","start":"2026-08-10T22:40:25.367772Z","end":"2026-08-10T22:40:25.468176Z","steps":["trace[1717756091] 'agreement among raft nodes before linearized reading' (duration: 100.350751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:25.468301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.613909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T22:40:25.468238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.601506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T22:40:25.468346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.800729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:40:25.468378Z","caller":"traceutil/trace.go:171","msg":"trace[186815323] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8172; }","duration":"124.85203ms","start":"2026-08-10T22:40:25.343516Z","end":"2026-08-10T22:40:25.468368Z","steps":["trace[186815323] 'agreement among raft nodes before linearized reading' (duration: 124.804179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:25.46839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.788854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:40:25.468374Z","caller":"traceutil/trace.go:171","msg":"trace[1185356197] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8172; }","duration":"177.751579ms","start":"2026-08-10T22:40:25.290605Z","end":"2026-08-10T22:40:25.468357Z","steps":["trace[1185356197] 'agreement among raft nodes before linearized reading' (duration: 177.586116ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:25.468453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.261724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:40:25.468477Z","caller":"traceutil/trace.go:171","msg":"trace[2021100893] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8172; }","duration":"113.286025ms","start":"2026-08-10T22:40:25.355185Z","end":"2026-08-10T22:40:25.468471Z","steps":["trace[2021100893] 'agreement among raft nodes before linearized reading' (duration: 113.250394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:25.468504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.424259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:40:25.468531Z","caller":"traceutil/trace.go:171","msg":"trace[398343746] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8172; }","duration":"139.473ms","start":"2026-08-10T22:40:25.32905Z","end":"2026-08-10T22:40:25.468523Z","steps":["trace[398343746] 'agreement among raft nodes before linearized reading' (duration: 139.431299ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:25.468581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.705009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T22:40:25.468593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.178182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:40:25.468668Z","caller":"traceutil/trace.go:171","msg":"trace[333370685] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8172; }","duration":"154.304645ms","start":"2026-08-10T22:40:25.314352Z","end":"2026-08-10T22:40:25.468657Z","steps":["trace[333370685] 'agreement among raft nodes before linearized reading' (duration: 154.205143ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:25.468668Z","caller":"traceutil/trace.go:171","msg":"trace[1378090028] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8172; }","duration":"213.832461ms","start":"2026-08-10T22:40:25.254822Z","end":"2026-08-10T22:40:25.468654Z","steps":["trace[1378090028] 'agreement among raft nodes before linearized reading' (duration: 213.704328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:25.468717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.180715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:40:25.46875Z","caller":"traceutil/trace.go:171","msg":"trace[1306739851] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8172; }","duration":"176.227477ms","start":"2026-08-10T22:40:25.292514Z","end":"2026-08-10T22:40:25.468742Z","steps":["trace[1306739851] 'agreement among raft nodes before linearized reading' (duration: 176.177026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:25.468782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.439411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:40:25.468135Z","caller":"traceutil/trace.go:171","msg":"trace[755430852] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8172; }","duration":"124.550023ms","start":"2026-08-10T22:40:25.34356Z","end":"2026-08-10T22:40:25.46811Z","steps":["trace[755430852] 'agreement among raft nodes before linearized reading' (duration: 124.298628ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:25.468807Z","caller":"traceutil/trace.go:171","msg":"trace[526788619] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8172; }","duration":"140.501993ms","start":"2026-08-10T22:40:25.328297Z","end":"2026-08-10T22:40:25.468799Z","steps":["trace[526788619] 'agreement among raft nodes before linearized reading' (duration: 140.466782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:25.468907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.650136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T22:40:25.468891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.867894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:40:25.46893Z","caller":"traceutil/trace.go:171","msg":"trace[699369779] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8172; }","duration":"140.707187ms","start":"2026-08-10T22:40:25.328215Z","end":"2026-08-10T22:40:25.468922Z","steps":["trace[699369779] 'agreement among raft nodes before linearized reading' (duration: 140.673686ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:25.468978Z","caller":"traceutil/trace.go:171","msg":"trace[880649053] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8172; }","duration":"194.976606ms","start":"2026-08-10T22:40:25.273981Z","end":"2026-08-10T22:40:25.468957Z","steps":["trace[880649053] 'agreement among raft nodes before linearized reading' (duration: 194.833063ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:25.468419Z","caller":"traceutil/trace.go:171","msg":"trace[1178701348] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8172; }","duration":"112.848736ms","start":"2026-08-10T22:40:25.355563Z","end":"2026-08-10T22:40:25.468411Z","steps":["trace[1178701348] 'agreement among raft nodes before linearized reading' (duration: 112.806185ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:25.46899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.107377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T22:40:25.469025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.831392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T22:40:25.469058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.920961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T22:40:25.469077Z","caller":"traceutil/trace.go:171","msg":"trace[728451330] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8172; }","duration":"214.216119ms","start":"2026-08-10T22:40:25.254837Z","end":"2026-08-10T22:40:25.469053Z","steps":["trace[728451330] 'agreement among raft nodes before linearized reading' (duration: 214.055106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:25.469101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.374597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:40:25.469112Z","caller":"traceutil/trace.go:171","msg":"trace[1876309527] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8172; }","duration":"212.989323ms","start":"2026-08-10T22:40:25.256108Z","end":"2026-08-10T22:40:25.469097Z","steps":["trace[1876309527] 'agreement among raft nodes before linearized reading' (duration: 212.900651ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:25.469117Z","caller":"traceutil/trace.go:171","msg":"trace[176334350] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8172; }","duration":"193.942024ms","start":"2026-08-10T22:40:25.275149Z","end":"2026-08-10T22:40:25.469091Z","steps":["trace[176334350] 'agreement among raft nodes before linearized reading' (duration: 193.776691ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:25.469173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.758015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T22:40:25.468979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.12425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:40:25.4692Z","caller":"traceutil/trace.go:171","msg":"trace[533202939] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8172; }","duration":"154.824526ms","start":"2026-08-10T22:40:25.314368Z","end":"2026-08-10T22:40:25.469193Z","steps":["trace[533202939] 'agreement among raft nodes before linearized reading' (duration: 154.781956ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:25.469225Z","caller":"traceutil/trace.go:171","msg":"trace[1464592239] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8172; }","duration":"195.402326ms","start":"2026-08-10T22:40:25.273806Z","end":"2026-08-10T22:40:25.469208Z","steps":["trace[1464592239] 'agreement among raft nodes before linearized reading' (duration: 195.11127ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:25.469134Z","caller":"traceutil/trace.go:171","msg":"trace[1906376785] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8172; }","duration":"154.429028ms","start":"2026-08-10T22:40:25.314695Z","end":"2026-08-10T22:40:25.469124Z","steps":["trace[1906376785] 'agreement among raft nodes before linearized reading' (duration: 154.376037ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:25.468344Z","caller":"traceutil/trace.go:171","msg":"trace[1173475884] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8172; }","duration":"111.67218ms","start":"2026-08-10T22:40:25.356656Z","end":"2026-08-10T22:40:25.468329Z","steps":["trace[1173475884] 'agreement among raft nodes before linearized reading' (duration: 111.610088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:26.160395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.341609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-10T22:40:26.160459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.490692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-10T22:40:26.160494Z","caller":"traceutil/trace.go:171","msg":"trace[1595942121] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8173; }","duration":"331.497642ms","start":"2026-08-10T22:40:25.828975Z","end":"2026-08-10T22:40:26.160473Z","steps":["trace[1595942121] 'range keys from in-memory index tree' (duration: 331.233437ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:26.16054Z","caller":"traceutil/trace.go:171","msg":"trace[403731835] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8173; }","duration":"331.609515ms","start":"2026-08-10T22:40:25.828909Z","end":"2026-08-10T22:40:26.160518Z","steps":["trace[403731835] 'range keys from in-memory index tree' (duration: 331.400221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:26.160548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:40:25.828966Z","time spent":"331.570524ms","remote":"127.0.0.1:38028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-10T22:40:26.160596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:40:25.828888Z","time spent":"331.693676ms","remote":"127.0.0.1:38028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-10T22:40:26.160726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.740992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-08-10T22:40:26.160767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.843288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-68h9n\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-10T22:40:26.160796Z","caller":"traceutil/trace.go:171","msg":"trace[703480874] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8173; }","duration":"127.845575ms","start":"2026-08-10T22:40:26.032936Z","end":"2026-08-10T22:40:26.160781Z","steps":["trace[703480874] 'range keys from in-memory index tree' (duration: 127.63871ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:26.160822Z","caller":"traceutil/trace.go:171","msg":"trace[1712001795] range","detail":"{range_begin:/registry/pods/openstack/keepalived-68h9n; range_end:; response_count:1; response_revision:8173; }","duration":"232.93056ms","start":"2026-08-10T22:40:25.927874Z","end":"2026-08-10T22:40:26.160805Z","steps":["trace[1712001795] 'range keys from in-memory index tree' (duration: 232.699885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:26.160338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.666664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:40:26.161125Z","caller":"traceutil/trace.go:171","msg":"trace[1337709512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8173; }","duration":"191.493561ms","start":"2026-08-10T22:40:25.96961Z","end":"2026-08-10T22:40:26.161103Z","steps":["trace[1337709512] 'range keys from in-memory index tree' (duration: 190.592072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:26.161127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.669878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-10T22:40:26.161193Z","caller":"traceutil/trace.go:171","msg":"trace[1087066376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8173; }","duration":"332.77371ms","start":"2026-08-10T22:40:25.828404Z","end":"2026-08-10T22:40:26.161178Z","steps":["trace[1087066376] 'range keys from in-memory index tree' (duration: 332.565805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:26.161449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:40:25.828387Z","time spent":"332.843411ms","remote":"127.0.0.1:38028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-10T22:40:44.056849Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6103} {"level":"info","ts":"2026-08-10T22:40:44.156856Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6103,"took":"98.118603ms","hash":2552435354,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":22982656,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-10T22:40:44.156923Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2552435354,"revision":6103,"compact-revision":2972} {"level":"info","ts":"2026-08-10T22:40:54.590999Z","caller":"traceutil/trace.go:171","msg":"trace[1602039399] transaction","detail":"{read_only:false; response_revision:8381; number_of_response:1; }","duration":"206.864029ms","start":"2026-08-10T22:40:54.384112Z","end":"2026-08-10T22:40:54.590976Z","steps":["trace[1602039399] 'process raft request' (duration: 179.235352ms)","trace[1602039399] 'compare' (duration: 27.486894ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:40:54.591372Z","caller":"traceutil/trace.go:171","msg":"trace[1047051856] transaction","detail":"{read_only:false; response_revision:8382; number_of_response:1; }","duration":"206.799758ms","start":"2026-08-10T22:40:54.384389Z","end":"2026-08-10T22:40:54.591189Z","steps":["trace[1047051856] 'process raft request' (duration: 206.555492ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:54.591438Z","caller":"traceutil/trace.go:171","msg":"trace[563072093] transaction","detail":"{read_only:false; response_revision:8385; number_of_response:1; }","duration":"205.923788ms","start":"2026-08-10T22:40:54.385492Z","end":"2026-08-10T22:40:54.591416Z","steps":["trace[563072093] 'process raft request' (duration: 205.685122ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:54.591659Z","caller":"traceutil/trace.go:171","msg":"trace[1566333905] linearizableReadLoop","detail":"{readStateIndex:8681; appliedIndex:8676; }","duration":"198.863603ms","start":"2026-08-10T22:40:54.392763Z","end":"2026-08-10T22:40:54.591627Z","steps":["trace[1566333905] 'read index received' (duration: 170.647374ms)","trace[1566333905] 'applied index is now lower than readState.Index' (duration: 28.215099ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:40:54.591708Z","caller":"traceutil/trace.go:171","msg":"trace[1659468994] transaction","detail":"{read_only:false; response_revision:8383; number_of_response:1; }","duration":"206.235065ms","start":"2026-08-10T22:40:54.38498Z","end":"2026-08-10T22:40:54.591215Z","steps":["trace[1659468994] 'process raft request' (duration: 206.100232ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:54.591805Z","caller":"traceutil/trace.go:171","msg":"trace[400869913] transaction","detail":"{read_only:false; response_revision:8384; number_of_response:1; }","duration":"205.796305ms","start":"2026-08-10T22:40:54.385419Z","end":"2026-08-10T22:40:54.591215Z","steps":["trace[400869913] 'process raft request' (duration: 205.723964ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:54.59193Z","caller":"traceutil/trace.go:171","msg":"trace[2063487272] transaction","detail":"{read_only:false; response_revision:8386; number_of_response:1; }","duration":"180.382657ms","start":"2026-08-10T22:40:54.411529Z","end":"2026-08-10T22:40:54.591912Z","steps":["trace[2063487272] 'process raft request' (duration: 179.828455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:54.592003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.218231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-68h9n\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-10T22:40:54.592062Z","caller":"traceutil/trace.go:171","msg":"trace[822174720] range","detail":"{range_begin:/registry/pods/openstack/keepalived-68h9n; range_end:; response_count:1; response_revision:8386; }","duration":"199.302232ms","start":"2026-08-10T22:40:54.392746Z","end":"2026-08-10T22:40:54.592048Z","steps":["trace[822174720] 'agreement among raft nodes before linearized reading' (duration: 199.125428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:07.629611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.306563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:41:07.629741Z","caller":"traceutil/trace.go:171","msg":"trace[490740026] transaction","detail":"{read_only:false; response_revision:8493; number_of_response:1; }","duration":"392.173654ms","start":"2026-08-10T22:41:07.237526Z","end":"2026-08-10T22:41:07.6297Z","steps":["trace[490740026] 'process raft request' (duration: 37.694388ms)","trace[490740026] 'compare' (duration: 354.104758ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:41:07.629803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:41:07.23751Z","time spent":"392.264077ms","remote":"127.0.0.1:37932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4630,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:41:09.215011Z","caller":"traceutil/trace.go:171","msg":"trace[1275911589] linearizableReadLoop","detail":"{readStateIndex:8812; appliedIndex:8811; }","duration":"133.300755ms","start":"2026-08-10T22:41:09.081572Z","end":"2026-08-10T22:41:09.214873Z","steps":["trace[1275911589] 'read index received' (duration: 133.123051ms)","trace[1275911589] 'applied index is now lower than readState.Index' (duration: 176.814µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:41:09.215278Z","caller":"traceutil/trace.go:171","msg":"trace[1579173230] transaction","detail":"{read_only:false; response_revision:8510; number_of_response:1; }","duration":"144.49355ms","start":"2026-08-10T22:41:09.070772Z","end":"2026-08-10T22:41:09.215265Z","steps":["trace[1579173230] 'process raft request' (duration: 143.976319ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:09.215639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.03737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4726"} {"level":"info","ts":"2026-08-10T22:41:09.215968Z","caller":"traceutil/trace.go:171","msg":"trace[1770576890] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:8510; }","duration":"134.172863ms","start":"2026-08-10T22:41:09.081539Z","end":"2026-08-10T22:41:09.215712Z","steps":["trace[1770576890] 'agreement among raft nodes before linearized reading' (duration: 133.907557ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:55.187593Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002415.wal"} {"level":"info","ts":"2026-08-10T22:43:15.419176Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8cf6035374726417","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-10T22:43:15.424377Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-10T22:43:15.424551Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-10T22:45:44.062439Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8277} {"level":"info","ts":"2026-08-10T22:45:44.144722Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8277,"took":"79.883787ms","hash":2461005197,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":26853376,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-10T22:45:44.144803Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2461005197,"revision":8277,"compact-revision":6103} {"level":"info","ts":"2026-08-10T22:50:44.068807Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10996} {"level":"info","ts":"2026-08-10T22:50:44.152662Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10996,"took":"81.463341ms","hash":4213413283,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":27639808,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-10T22:50:44.152743Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4213413283,"revision":10996,"compact-revision":8277} {"level":"info","ts":"2026-08-10T22:51:44.158263Z","caller":"traceutil/trace.go:171","msg":"trace[1184095145] linearizableReadLoop","detail":"{readStateIndex:13908; appliedIndex:13907; }","duration":"106.985591ms","start":"2026-08-10T22:51:44.051241Z","end":"2026-08-10T22:51:44.158227Z","steps":["trace[1184095145] 'read index received' (duration: 106.201974ms)","trace[1184095145] 'applied index is now lower than readState.Index' (duration: 782.107µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:51:44.158429Z","caller":"traceutil/trace.go:171","msg":"trace[1877609585] transaction","detail":"{read_only:false; response_revision:13447; number_of_response:1; }","duration":"152.002201ms","start":"2026-08-10T22:51:44.006413Z","end":"2026-08-10T22:51:44.158415Z","steps":["trace[1877609585] 'process raft request' (duration: 151.10656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:51:44.158926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.655667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T22:51:44.159001Z","caller":"traceutil/trace.go:171","msg":"trace[834280146] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:13447; }","duration":"107.778779ms","start":"2026-08-10T22:51:44.051203Z","end":"2026-08-10T22:51:44.158982Z","steps":["trace[834280146] 'agreement among raft nodes before linearized reading' (duration: 107.631436ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:52:06.421127Z","caller":"traceutil/trace.go:171","msg":"trace[347124657] linearizableReadLoop","detail":"{readStateIndex:14036; appliedIndex:14033; }","duration":"110.838826ms","start":"2026-08-10T22:52:06.310269Z","end":"2026-08-10T22:52:06.421108Z","steps":["trace[347124657] 'read index received' (duration: 5.48621ms)","trace[347124657] 'applied index is now lower than readState.Index' (duration: 105.351966ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:52:06.421173Z","caller":"traceutil/trace.go:171","msg":"trace[1686560923] transaction","detail":"{read_only:false; response_revision:13570; number_of_response:1; }","duration":"148.465673ms","start":"2026-08-10T22:52:06.272684Z","end":"2026-08-10T22:52:06.42115Z","steps":["trace[1686560923] 'process raft request' (duration: 148.387431ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:52:06.421176Z","caller":"traceutil/trace.go:171","msg":"trace[1141091658] transaction","detail":"{read_only:false; response_revision:13569; number_of_response:1; }","duration":"151.221034ms","start":"2026-08-10T22:52:06.269916Z","end":"2026-08-10T22:52:06.421137Z","steps":["trace[1141091658] 'process raft request' (duration: 146.235654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:52:06.421251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.969119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:52:06.421285Z","caller":"traceutil/trace.go:171","msg":"trace[1120840346] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:13570; }","duration":"111.081762ms","start":"2026-08-10T22:52:06.310196Z","end":"2026-08-10T22:52:06.421277Z","steps":["trace[1120840346] 'agreement among raft nodes before linearized reading' (duration: 110.99986ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:52:21.337447Z","caller":"traceutil/trace.go:171","msg":"trace[1162242518] transaction","detail":"{read_only:false; response_revision:13658; number_of_response:1; }","duration":"202.96562ms","start":"2026-08-10T22:52:21.134452Z","end":"2026-08-10T22:52:21.337417Z","steps":["trace[1162242518] 'process raft request' (duration: 202.778436ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:52:21.345679Z","caller":"traceutil/trace.go:171","msg":"trace[2007080014] transaction","detail":"{read_only:false; response_revision:13659; number_of_response:1; }","duration":"189.584736ms","start":"2026-08-10T22:52:21.156068Z","end":"2026-08-10T22:52:21.345653Z","steps":["trace[2007080014] 'process raft request' (duration: 189.438773ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:53:52.894919Z","caller":"traceutil/trace.go:171","msg":"trace[1723548971] transaction","detail":"{read_only:false; response_revision:14694; number_of_response:1; }","duration":"160.701831ms","start":"2026-08-10T22:53:52.734186Z","end":"2026-08-10T22:53:52.894888Z","steps":["trace[1723548971] 'process raft request' (duration: 160.175599ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:53:53.345393Z","caller":"traceutil/trace.go:171","msg":"trace[817773964] linearizableReadLoop","detail":"{readStateIndex:15198; appliedIndex:15197; }","duration":"269.854758ms","start":"2026-08-10T22:53:53.075507Z","end":"2026-08-10T22:53:53.345361Z","steps":["trace[817773964] 'read index received' (duration: 183.462761ms)","trace[817773964] 'applied index is now lower than readState.Index' (duration: 86.390757ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:53:53.345522Z","caller":"traceutil/trace.go:171","msg":"trace[637571806] transaction","detail":"{read_only:false; response_revision:14697; number_of_response:1; }","duration":"327.870392ms","start":"2026-08-10T22:53:53.017636Z","end":"2026-08-10T22:53:53.345507Z","steps":["trace[637571806] 'process raft request' (duration: 241.320172ms)","trace[637571806] 'compare' (duration: 86.262744ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:53:53.345586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.059642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T22:53:53.345627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:53:53.017612Z","time spent":"327.937464ms","remote":"127.0.0.1:38028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:53:53.345686Z","caller":"traceutil/trace.go:171","msg":"trace[366427925] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14697; }","duration":"270.203915ms","start":"2026-08-10T22:53:53.075461Z","end":"2026-08-10T22:53:53.345665Z","steps":["trace[366427925] 'agreement among raft nodes before linearized reading' (duration: 270.064222ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:53:53.626727Z","caller":"traceutil/trace.go:171","msg":"trace[1096088811] linearizableReadLoop","detail":"{readStateIndex:15199; appliedIndex:15198; }","duration":"281.228239ms","start":"2026-08-10T22:53:53.345473Z","end":"2026-08-10T22:53:53.626702Z","steps":["trace[1096088811] 'read index received' (duration: 272.784583ms)","trace[1096088811] 'applied index is now lower than readState.Index' (duration: 8.442706ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:53:53.626774Z","caller":"traceutil/trace.go:171","msg":"trace[686504105] transaction","detail":"{read_only:false; response_revision:14698; number_of_response:1; }","duration":"388.584707ms","start":"2026-08-10T22:53:53.238157Z","end":"2026-08-10T22:53:53.626742Z","steps":["trace[686504105] 'process raft request' (duration: 380.145241ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:53:53.62683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.798008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-08-10T22:53:53.626852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.660874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:53:53.626912Z","caller":"traceutil/trace.go:171","msg":"trace[1752671019] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14698; }","duration":"402.915531ms","start":"2026-08-10T22:53:53.223985Z","end":"2026-08-10T22:53:53.6269Z","steps":["trace[1752671019] 'agreement among raft nodes before linearized reading' (duration: 402.720377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:53:53.626916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.839546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-hg9sr\" ","response":"range_response_count:1 size:9799"} {"level":"warn","ts":"2026-08-10T22:53:53.626919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:53:53.23814Z","time spent":"388.701789ms","remote":"127.0.0.1:37918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18664,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:53:53.626935Z","caller":"traceutil/trace.go:171","msg":"trace[23451925] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14698; }","duration":"401.760667ms","start":"2026-08-10T22:53:53.225153Z","end":"2026-08-10T22:53:53.626914Z","steps":["trace[23451925] 'agreement among raft nodes before linearized reading' (duration: 401.613994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:53:53.626946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:53:53.223968Z","time spent":"402.969892ms","remote":"127.0.0.1:37912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":688,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-08-10T22:53:53.626981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:53:53.225148Z","time spent":"401.825188ms","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-08-10T22:53:53.627Z","caller":"traceutil/trace.go:171","msg":"trace[1076529548] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-hg9sr; range_end:; response_count:1; response_revision:14698; }","duration":"436.917418ms","start":"2026-08-10T22:53:53.190021Z","end":"2026-08-10T22:53:53.626938Z","steps":["trace[1076529548] 'agreement among raft nodes before linearized reading' (duration: 436.811216ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:53:53.627041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:53:53.189985Z","time spent":"437.047501ms","remote":"127.0.0.1:37932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9823,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-hg9sr\" "} {"level":"warn","ts":"2026-08-10T22:53:53.627172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.136308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:53:53.627244Z","caller":"traceutil/trace.go:171","msg":"trace[304509799] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14698; }","duration":"551.27692ms","start":"2026-08-10T22:53:53.075946Z","end":"2026-08-10T22:53:53.627223Z","steps":["trace[304509799] 'agreement among raft nodes before linearized reading' (duration: 551.108747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:53:53.627263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.101076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-10T22:53:53.627294Z","caller":"traceutil/trace.go:171","msg":"trace[1122680434] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14698; }","duration":"403.163407ms","start":"2026-08-10T22:53:53.224122Z","end":"2026-08-10T22:53:53.627286Z","steps":["trace[1122680434] 'agreement among raft nodes before linearized reading' (duration: 403.060095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:53:53.6273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:53:53.075943Z","time spent":"551.344792ms","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-08-10T22:53:53.627321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:53:53.224117Z","time spent":"403.197607ms","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5547,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-08-10T22:53:53.627405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.986274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:53:53.627478Z","caller":"traceutil/trace.go:171","msg":"trace[1196653822] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:14698; }","duration":"236.105976ms","start":"2026-08-10T22:53:53.391349Z","end":"2026-08-10T22:53:53.627455Z","steps":["trace[1196653822] 'agreement among raft nodes before linearized reading' (duration: 236.017344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:53:53.627508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.59081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-08-10T22:53:53.627554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.62278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-10T22:53:53.627572Z","caller":"traceutil/trace.go:171","msg":"trace[1773964543] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14698; }","duration":"486.704703ms","start":"2026-08-10T22:53:53.140851Z","end":"2026-08-10T22:53:53.627556Z","steps":["trace[1773964543] 'agreement among raft nodes before linearized reading' (duration: 486.534459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:53:53.627613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:53:53.140829Z","time spent":"486.775774ms","remote":"127.0.0.1:37912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":688,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-08-10T22:53:53.627609Z","caller":"traceutil/trace.go:171","msg":"trace[475094002] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14698; }","duration":"547.684901ms","start":"2026-08-10T22:53:53.07991Z","end":"2026-08-10T22:53:53.627595Z","steps":["trace[475094002] 'agreement among raft nodes before linearized reading' (duration: 547.517518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:53:53.627655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:53:53.079905Z","time spent":"547.740062ms","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5547,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-08-10T22:53:53.627821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.009866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:53:53.627863Z","caller":"traceutil/trace.go:171","msg":"trace[127927648] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14698; }","duration":"552.061338ms","start":"2026-08-10T22:53:53.075792Z","end":"2026-08-10T22:53:53.627853Z","steps":["trace[127927648] 'agreement among raft nodes before linearized reading' (duration: 551.995677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:53:53.62789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:53:53.075787Z","time spent":"552.097169ms","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"info","ts":"2026-08-10T22:55:44.07621Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13036} {"level":"info","ts":"2026-08-10T22:55:44.154968Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13036,"took":"75.469198ms","hash":4042795737,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":30539776,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-10T22:55:44.155089Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4042795737,"revision":13036,"compact-revision":10996} {"level":"warn","ts":"2026-08-10T22:59:23.336777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.793652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T22:59:23.3369Z","caller":"traceutil/trace.go:171","msg":"trace[1991055910] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:17181; }","duration":"101.947045ms","start":"2026-08-10T22:59:23.234921Z","end":"2026-08-10T22:59:23.336868Z","steps":["trace[1991055910] 'count revisions from in-memory index tree' (duration: 101.72148ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:59:28.26639Z","caller":"traceutil/trace.go:171","msg":"trace[896296653] linearizableReadLoop","detail":"{readStateIndex:17798; appliedIndex:17797; }","duration":"155.756045ms","start":"2026-08-10T22:59:28.110615Z","end":"2026-08-10T22:59:28.266371Z","steps":["trace[896296653] 'read index received' (duration: 155.581911ms)","trace[896296653] 'applied index is now lower than readState.Index' (duration: 173.534µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:59:28.266487Z","caller":"traceutil/trace.go:171","msg":"trace[2040080042] transaction","detail":"{read_only:false; response_revision:17214; number_of_response:1; }","duration":"178.621666ms","start":"2026-08-10T22:59:28.087853Z","end":"2026-08-10T22:59:28.266475Z","steps":["trace[2040080042] 'process raft request' (duration: 178.40551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:59:28.266687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.062512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-10T22:59:28.266701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.001274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T22:59:28.266729Z","caller":"traceutil/trace.go:171","msg":"trace[1015426009] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17214; }","duration":"156.149014ms","start":"2026-08-10T22:59:28.11057Z","end":"2026-08-10T22:59:28.266719Z","steps":["trace[1015426009] 'agreement among raft nodes before linearized reading' (duration: 156.023171ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:59:28.266809Z","caller":"traceutil/trace.go:171","msg":"trace[30361540] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17214; }","duration":"116.126866ms","start":"2026-08-10T22:59:28.150634Z","end":"2026-08-10T22:59:28.266761Z","steps":["trace[30361540] 'agreement among raft nodes before linearized reading' (duration: 115.964462ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:59:28.8023Z","caller":"traceutil/trace.go:171","msg":"trace[23699560] transaction","detail":"{read_only:false; response_revision:17216; number_of_response:1; }","duration":"179.117577ms","start":"2026-08-10T22:59:28.623153Z","end":"2026-08-10T22:59:28.80227Z","steps":["trace[23699560] 'process raft request' (duration: 178.951703ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:59:28.816385Z","caller":"traceutil/trace.go:171","msg":"trace[1300664720] transaction","detail":"{read_only:false; response_revision:17217; number_of_response:1; }","duration":"191.252792ms","start":"2026-08-10T22:59:28.625107Z","end":"2026-08-10T22:59:28.81636Z","steps":["trace[1300664720] 'process raft request' (duration: 191.110589ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:59:29.10024Z","caller":"traceutil/trace.go:171","msg":"trace[523336411] transaction","detail":"{read_only:false; response_revision:17218; number_of_response:1; }","duration":"116.991555ms","start":"2026-08-10T22:59:28.983185Z","end":"2026-08-10T22:59:29.100176Z","steps":["trace[523336411] 'process raft request' (duration: 116.729599ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:00:44.083398Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15393} {"level":"info","ts":"2026-08-10T23:00:44.178161Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15393,"took":"92.064209ms","hash":920413305,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":33832960,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-10T23:00:44.178228Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":920413305,"revision":15393,"compact-revision":13036} {"level":"info","ts":"2026-08-10T23:03:33.376837Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8cf6035374726417","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-10T23:03:33.383713Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-10T23:03:33.383795Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-10T23:04:52.484578Z","caller":"traceutil/trace.go:171","msg":"trace[1297356935] transaction","detail":"{read_only:false; response_revision:19812; number_of_response:1; }","duration":"130.748877ms","start":"2026-08-10T23:04:52.353796Z","end":"2026-08-10T23:04:52.484545Z","steps":["trace[1297356935] 'process raft request' (duration: 130.565963ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:04:52.485111Z","caller":"traceutil/trace.go:171","msg":"trace[580851371] transaction","detail":"{read_only:false; response_revision:19813; number_of_response:1; }","duration":"130.970272ms","start":"2026-08-10T23:04:52.354113Z","end":"2026-08-10T23:04:52.485084Z","steps":["trace[580851371] 'process raft request' (duration: 130.808479ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:04:58.241141Z","caller":"traceutil/trace.go:171","msg":"trace[423630694] transaction","detail":"{read_only:false; response_revision:19840; number_of_response:1; }","duration":"344.537232ms","start":"2026-08-10T23:04:57.896573Z","end":"2026-08-10T23:04:58.24111Z","steps":["trace[423630694] 'process raft request' (duration: 344.344757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:04:58.241316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:04:57.896539Z","time spent":"344.684425ms","remote":"127.0.0.1:38028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:04:58.472227Z","caller":"traceutil/trace.go:171","msg":"trace[968350903] transaction","detail":"{read_only:false; response_revision:19841; number_of_response:1; }","duration":"117.783509ms","start":"2026-08-10T23:04:58.354426Z","end":"2026-08-10T23:04:58.47221Z","steps":["trace[968350903] 'process raft request' (duration: 117.657656ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:04:58.619404Z","caller":"traceutil/trace.go:171","msg":"trace[1385461136] transaction","detail":"{read_only:false; response_revision:19843; number_of_response:1; }","duration":"108.515154ms","start":"2026-08-10T23:04:58.51082Z","end":"2026-08-10T23:04:58.619335Z","steps":["trace[1385461136] 'process raft request' (duration: 108.439242ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:04:58.619456Z","caller":"traceutil/trace.go:171","msg":"trace[56450689] transaction","detail":"{read_only:false; response_revision:19842; number_of_response:1; }","duration":"110.016397ms","start":"2026-08-10T23:04:58.509402Z","end":"2026-08-10T23:04:58.619419Z","steps":["trace[56450689] 'process raft request' (duration: 108.38587ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:04:58.788336Z","caller":"traceutil/trace.go:171","msg":"trace[959798549] transaction","detail":"{read_only:false; response_revision:19844; number_of_response:1; }","duration":"120.004809ms","start":"2026-08-10T23:04:58.668311Z","end":"2026-08-10T23:04:58.788316Z","steps":["trace[959798549] 'process raft request' (duration: 113.133037ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:04:58.790339Z","caller":"traceutil/trace.go:171","msg":"trace[300508146] transaction","detail":"{read_only:false; response_revision:19845; number_of_response:1; }","duration":"121.785857ms","start":"2026-08-10T23:04:58.668524Z","end":"2026-08-10T23:04:58.79031Z","steps":["trace[300508146] 'process raft request' (duration: 121.642294ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:04:59.104683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.442126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-10T23:04:59.104786Z","caller":"traceutil/trace.go:171","msg":"trace[942463175] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19845; }","duration":"184.593799ms","start":"2026-08-10T23:04:58.920166Z","end":"2026-08-10T23:04:59.10476Z","steps":["trace[942463175] 'range keys from in-memory index tree' (duration: 184.19371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:04:59.104827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.240728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:04:59.104909Z","caller":"traceutil/trace.go:171","msg":"trace[1249226916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19845; }","duration":"127.36093ms","start":"2026-08-10T23:04:58.977529Z","end":"2026-08-10T23:04:59.10489Z","steps":["trace[1249226916] 'range keys from in-memory index tree' (duration: 127.156056ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:04:59.267851Z","caller":"traceutil/trace.go:171","msg":"trace[1051331929] transaction","detail":"{read_only:false; response_revision:19846; number_of_response:1; }","duration":"158.738635ms","start":"2026-08-10T23:04:59.109082Z","end":"2026-08-10T23:04:59.26782Z","steps":["trace[1051331929] 'process raft request' (duration: 158.562011ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:05:44.089587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18084} {"level":"info","ts":"2026-08-10T23:05:44.213881Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18084,"took":"120.959959ms","hash":3782876549,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":31174656,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-10T23:05:44.213954Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3782876549,"revision":18084,"compact-revision":15393} {"level":"warn","ts":"2026-08-10T23:06:47.642236Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212409171870829313,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-10T23:06:47.995509Z","caller":"traceutil/trace.go:171","msg":"trace[1529053995] transaction","detail":"{read_only:false; response_revision:20817; number_of_response:1; }","duration":"864.199424ms","start":"2026-08-10T23:06:47.131289Z","end":"2026-08-10T23:06:47.995488Z","steps":["trace[1529053995] 'process raft request' (duration: 864.059881ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:06:47.995635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:06:47.131267Z","time spent":"864.303375ms","remote":"127.0.0.1:38028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:06:48.028099Z","caller":"traceutil/trace.go:171","msg":"trace[1123191233] transaction","detail":"{read_only:false; response_revision:20818; number_of_response:1; }","duration":"894.695849ms","start":"2026-08-10T23:06:47.133362Z","end":"2026-08-10T23:06:48.028058Z","steps":["trace[1123191233] 'process raft request' (duration: 894.381492ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:06:48.028094Z","caller":"traceutil/trace.go:171","msg":"trace[75347742] transaction","detail":"{read_only:false; response_revision:20819; number_of_response:1; }","duration":"857.077856ms","start":"2026-08-10T23:06:47.170984Z","end":"2026-08-10T23:06:48.028062Z","steps":["trace[75347742] 'process raft request' (duration: 856.962083ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:06:48.028238Z","caller":"traceutil/trace.go:171","msg":"trace[1758623655] linearizableReadLoop","detail":"{readStateIndex:21521; appliedIndex:21517; }","duration":"887.1289ms","start":"2026-08-10T23:06:47.141087Z","end":"2026-08-10T23:06:48.028216Z","steps":["trace[1758623655] 'read index received' (duration: 854.344044ms)","trace[1758623655] 'applied index is now lower than readState.Index' (duration: 32.782576ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:06:48.028274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:06:47.133352Z","time spent":"894.834392ms","remote":"127.0.0.1:38028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:06:48.028318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:06:47.170954Z","time spent":"857.244779ms","remote":"127.0.0.1:38028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:06:48.028337Z","caller":"traceutil/trace.go:171","msg":"trace[189326399] transaction","detail":"{read_only:false; response_revision:20820; number_of_response:1; }","duration":"851.00184ms","start":"2026-08-10T23:06:47.177323Z","end":"2026-08-10T23:06:48.028325Z","steps":["trace[189326399] 'process raft request' (duration: 850.678292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:06:48.028543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:06:47.177313Z","time spent":"851.190444ms","remote":"127.0.0.1:38028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:06:48.02851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.423388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.238\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-10T23:06:48.028598Z","caller":"traceutil/trace.go:171","msg":"trace[620322475] range","detail":"{range_begin:/registry/masterleases/199.19.213.238; range_end:; response_count:1; response_revision:20820; }","duration":"887.5483ms","start":"2026-08-10T23:06:47.141035Z","end":"2026-08-10T23:06:48.028583Z","steps":["trace[620322475] 'agreement among raft nodes before linearized reading' (duration: 887.319214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:06:48.028663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:06:47.141016Z","time spent":"887.635853ms","remote":"127.0.0.1:37780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":164,"request content":"key:\"/registry/masterleases/199.19.213.238\" "} {"level":"warn","ts":"2026-08-10T23:06:48.028718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.976296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:06:48.028743Z","caller":"traceutil/trace.go:171","msg":"trace[1985031686] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:20820; }","duration":"621.028396ms","start":"2026-08-10T23:06:47.407705Z","end":"2026-08-10T23:06:48.028733Z","steps":["trace[1985031686] 'agreement among raft nodes before linearized reading' (duration: 620.976965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:06:48.02876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:06:47.407688Z","time spent":"621.067408ms","remote":"127.0.0.1:41388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-08-10T23:06:48.028737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.78177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-08-10T23:06:48.028733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.826633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:1 size:12687"} {"level":"info","ts":"2026-08-10T23:06:48.028822Z","caller":"traceutil/trace.go:171","msg":"trace[867166769] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:20820; }","duration":"559.922353ms","start":"2026-08-10T23:06:47.468874Z","end":"2026-08-10T23:06:48.028796Z","steps":["trace[867166769] 'agreement among raft nodes before linearized reading' (duration: 559.606256ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:06:48.028844Z","caller":"traceutil/trace.go:171","msg":"trace[918467440] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:1; response_revision:20820; }","duration":"843.979107ms","start":"2026-08-10T23:06:47.184849Z","end":"2026-08-10T23:06:48.028828Z","steps":["trace[918467440] 'agreement among raft nodes before linearized reading' (duration: 843.548856ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:06:48.028894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:06:47.468859Z","time spent":"560.017396ms","remote":"127.0.0.1:33772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":143,"response size":33,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-08-10T23:06:48.02893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:06:47.184833Z","time spent":"844.082538ms","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12711,"request content":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" "} {"level":"warn","ts":"2026-08-10T23:06:48.029131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.117686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-10T23:06:48.029194Z","caller":"traceutil/trace.go:171","msg":"trace[1799053775] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20820; }","duration":"215.180817ms","start":"2026-08-10T23:06:47.813995Z","end":"2026-08-10T23:06:48.029176Z","steps":["trace[1799053775] 'agreement among raft nodes before linearized reading' (duration: 215.001353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:06:48.029138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.131097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:06:48.029271Z","caller":"traceutil/trace.go:171","msg":"trace[66229631] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:20820; }","duration":"843.30491ms","start":"2026-08-10T23:06:47.185959Z","end":"2026-08-10T23:06:48.029264Z","steps":["trace[66229631] 'agreement among raft nodes before linearized reading' (duration: 843.072855ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:06:48.029299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:06:47.185945Z","time spent":"843.346732ms","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/octavia-ks-user\" "} {"level":"info","ts":"2026-08-10T23:08:39.122086Z","caller":"traceutil/trace.go:171","msg":"trace[1369331784] linearizableReadLoop","detail":"{readStateIndex:22984; appliedIndex:22983; }","duration":"142.47579ms","start":"2026-08-10T23:08:38.979584Z","end":"2026-08-10T23:08:39.12206Z","steps":["trace[1369331784] 'read index received' (duration: 142.305916ms)","trace[1369331784] 'applied index is now lower than readState.Index' (duration: 168.664µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:08:39.122151Z","caller":"traceutil/trace.go:171","msg":"trace[296809757] transaction","detail":"{read_only:false; response_revision:22246; number_of_response:1; }","duration":"251.280581ms","start":"2026-08-10T23:08:38.870829Z","end":"2026-08-10T23:08:39.122109Z","steps":["trace[296809757] 'process raft request' (duration: 251.030015ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:08:39.122221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.622002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:08:39.122262Z","caller":"traceutil/trace.go:171","msg":"trace[1909345095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22246; }","duration":"142.717646ms","start":"2026-08-10T23:08:38.979534Z","end":"2026-08-10T23:08:39.122251Z","steps":["trace[1909345095] 'agreement among raft nodes before linearized reading' (duration: 142.634094ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:10:44.097082Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20405} {"level":"info","ts":"2026-08-10T23:10:44.212952Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20405,"took":"111.854178ms","hash":3828621313,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":44048384,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-10T23:10:44.213033Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3828621313,"revision":20405,"compact-revision":18084} {"level":"info","ts":"2026-08-10T23:15:44.103641Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23486} {"level":"info","ts":"2026-08-10T23:15:44.229237Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23486,"took":"122.676456ms","hash":1329743428,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":31322112,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-10T23:15:44.229305Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1329743428,"revision":23486,"compact-revision":20405}