{"level":"warn","ts":"2026-10-17T10:54:39.934011Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-17T10:54:39.934104Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.93: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://162.253.55.93:2380","--initial-cluster=instance=https://162.253.55.93:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.93:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.93: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-10-17T10:54:39.934186Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-17T10:54:39.934201Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-10-17T10:54:39.934228Z","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-10-17T10:54:39.934956Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"]} {"level":"info","ts":"2026-10-17T10:54:39.935066Z","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://162.253.55.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.93: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-10-17T10:54:39.937988Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.640635ms"} {"level":"info","ts":"2026-10-17T10:54:39.944222Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ea06c5748459930b","cluster-id":"49d4e1d71c75bc5"} {"level":"info","ts":"2026-10-17T10:54:39.944334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=()"} {"level":"info","ts":"2026-10-17T10:54:39.944364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 0"} {"level":"info","ts":"2026-10-17T10:54:39.944388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ea06c5748459930b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-17T10:54:39.944407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 1"} {"level":"info","ts":"2026-10-17T10:54:39.944469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"warn","ts":"2026-10-17T10:54:39.948985Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-17T10:54:39.951865Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-17T10:54:39.953619Z","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-10-17T10:54:39.956726Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ea06c5748459930b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-17T10:54:39.957159Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ea06c5748459930b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-17T10:54:39.957293Z","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-10-17T10:54:39.957426Z","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-10-17T10:54:39.957446Z","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-10-17T10:54:39.959711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"info","ts":"2026-10-17T10:54:39.96005Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","added-peer-id":"ea06c5748459930b","added-peer-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-10-17T10:54:39.961734Z","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-10-17T10:54:39.962053Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ea06c5748459930b","initial-advertise-peer-urls":["https://162.253.55.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-17T10:54:39.962086Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-17T10:54:39.962573Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-10-17T10:54:39.962671Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-10-17T10:54:40.744998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b is starting a new election at term 1"} {"level":"info","ts":"2026-10-17T10:54:40.745065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-17T10:54:40.745089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgPreVoteResp from ea06c5748459930b at term 1"} {"level":"info","ts":"2026-10-17T10:54:40.745136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became candidate at term 2"} {"level":"info","ts":"2026-10-17T10:54:40.745148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgVoteResp from ea06c5748459930b at term 2"} {"level":"info","ts":"2026-10-17T10:54:40.745163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became leader at term 2"} {"level":"info","ts":"2026-10-17T10:54:40.745176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ea06c5748459930b elected leader ea06c5748459930b at term 2"} {"level":"info","ts":"2026-10-17T10:54:40.74661Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ea06c5748459930b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.93:2379]}","request-path":"/0/members/ea06c5748459930b/attributes","cluster-id":"49d4e1d71c75bc5","publish-timeout":"7s"} {"level":"info","ts":"2026-10-17T10:54:40.746642Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:54:40.746621Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T10:54:40.746935Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T10:54:40.747067Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-17T10:54:40.747166Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-17T10:54:40.748674Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-17T10:54:40.750175Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.93:2379"} {"level":"info","ts":"2026-10-17T10:54:40.765322Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:54:40.765525Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:54:40.765589Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T10:55:25.670861Z","caller":"traceutil/trace.go:171","msg":"trace[1013056074] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"113.07277ms","start":"2026-10-17T10:55:25.557757Z","end":"2026-10-17T10:55:25.67083Z","steps":["trace[1013056074] 'process raft request' (duration: 112.862574ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:32.197046Z","caller":"traceutil/trace.go:171","msg":"trace[1005273323] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"203.496679ms","start":"2026-10-17T10:55:31.993522Z","end":"2026-10-17T10:55:32.197019Z","steps":["trace[1005273323] 'read index received' (duration: 202.710844ms)","trace[1005273323] 'applied index is now lower than readState.Index' (duration: 784.375µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:55:32.197266Z","caller":"traceutil/trace.go:171","msg":"trace[1762858965] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"226.624712ms","start":"2026-10-17T10:55:31.970589Z","end":"2026-10-17T10:55:32.197214Z","steps":["trace[1762858965] 'process raft request' (duration: 225.909759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:32.197307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.756568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6gp28\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-17T10:55:32.197397Z","caller":"traceutil/trace.go:171","msg":"trace[691013944] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6gp28; range_end:; response_count:1; response_revision:605; }","duration":"203.890811ms","start":"2026-10-17T10:55:31.993494Z","end":"2026-10-17T10:55:32.197385Z","steps":["trace[691013944] 'agreement among raft nodes before linearized reading' (duration: 203.652874ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:32.506957Z","caller":"traceutil/trace.go:171","msg":"trace[167010939] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"286.607793ms","start":"2026-10-17T10:55:32.220304Z","end":"2026-10-17T10:55:32.506912Z","steps":["trace[167010939] 'process raft request' (duration: 286.329045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:33.624319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.07004383s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-17T10:55:33.624551Z","caller":"traceutil/trace.go:171","msg":"trace[1339953758] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"618.655336ms","start":"2026-10-17T10:55:33.005863Z","end":"2026-10-17T10:55:33.624518Z","steps":["trace[1339953758] 'read index received' (duration: 68.623µs)","trace[1339953758] 'applied index is now lower than readState.Index' (duration: 618.583933ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:55:33.624537Z","caller":"traceutil/trace.go:171","msg":"trace[621325099] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"1.351624834s","start":"2026-10-17T10:55:32.27283Z","end":"2026-10-17T10:55:33.624455Z","steps":["trace[621325099] 'process raft request' (duration: 280.948093ms)","trace[621325099] 'compare' (duration: 1.069791922s)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:55:33.624704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.404826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T10:55:33.624717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.377482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:55:33.624739Z","caller":"traceutil/trace.go:171","msg":"trace[227239397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"528.464507ms","start":"2026-10-17T10:55:33.096264Z","end":"2026-10-17T10:55:33.624729Z","steps":["trace[227239397] 'agreement among raft nodes before linearized reading' (duration: 528.381205ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:55:33.624762Z","caller":"traceutil/trace.go:171","msg":"trace[2039892419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"562.463335ms","start":"2026-10-17T10:55:33.062286Z","end":"2026-10-17T10:55:33.624749Z","steps":["trace[2039892419] 'agreement among raft nodes before linearized reading' (duration: 562.396383ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:33.624767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:33.09625Z","time spent":"528.510019ms","remote":"127.0.0.1:55642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-17T10:55:33.624833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:33.062264Z","time spent":"562.560758ms","remote":"127.0.0.1:55636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-17T10:55:33.624739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:32.272806Z","time spent":"1.351837751s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-17T10:55:33.62484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.960105ms","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-10-17T10:55:33.624951Z","caller":"traceutil/trace.go:171","msg":"trace[1121523190] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"619.122439ms","start":"2026-10-17T10:55:33.005802Z","end":"2026-10-17T10:55:33.624924Z","steps":["trace[1121523190] 'agreement among raft nodes before linearized reading' (duration: 618.791279ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:33.625001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:33.005751Z","time spent":"619.240913ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-17T10:55:33.62496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.725442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-17T10:55:33.625175Z","caller":"traceutil/trace.go:171","msg":"trace[1554089125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"526.9916ms","start":"2026-10-17T10:55:33.09816Z","end":"2026-10-17T10:55:33.625151Z","steps":["trace[1554089125] 'agreement among raft nodes before linearized reading' (duration: 526.489905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:33.625265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.67223ms","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-10-17T10:55:33.625317Z","caller":"traceutil/trace.go:171","msg":"trace[1285759900] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"567.755092ms","start":"2026-10-17T10:55:33.057549Z","end":"2026-10-17T10:55:33.625304Z","steps":["trace[1285759900] 'agreement among raft nodes before linearized reading' (duration: 567.098372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:33.625346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:33.057489Z","time spent":"567.848425ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-17T10:55:33.625414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:33.09815Z","time spent":"527.204917ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-17T10:55:49.524255Z","caller":"traceutil/trace.go:171","msg":"trace[1014438752] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:803; }","duration":"345.565551ms","start":"2026-10-17T10:55:49.178658Z","end":"2026-10-17T10:55:49.524224Z","steps":["trace[1014438752] 'read index received' (duration: 345.380735ms)","trace[1014438752] 'applied index is now lower than readState.Index' (duration: 183.576µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:55:49.524301Z","caller":"traceutil/trace.go:171","msg":"trace[1042312403] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"368.942321ms","start":"2026-10-17T10:55:49.155319Z","end":"2026-10-17T10:55:49.524262Z","steps":["trace[1042312403] 'process raft request' (duration: 368.692433ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:49.524397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.718365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T10:55:49.524441Z","caller":"traceutil/trace.go:171","msg":"trace[322252600] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:776; }","duration":"345.804428ms","start":"2026-10-17T10:55:49.178622Z","end":"2026-10-17T10:55:49.524427Z","steps":["trace[322252600] 'agreement among raft nodes before linearized reading' (duration: 345.721905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:55:49.524491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:49.178604Z","time spent":"345.873451ms","remote":"127.0.0.1:55838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-10-17T10:55:49.524685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:55:49.155294Z","time spent":"369.063095ms","remote":"127.0.0.1:55888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:56:18.290922Z","caller":"traceutil/trace.go:171","msg":"trace[1445288049] linearizableReadLoop","detail":"{readStateIndex:1208; appliedIndex:1207; }","duration":"208.225559ms","start":"2026-10-17T10:56:18.082671Z","end":"2026-10-17T10:56:18.290897Z","steps":["trace[1445288049] 'read index received' (duration: 166.843117ms)","trace[1445288049] 'applied index is now lower than readState.Index' (duration: 41.381532ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:56:18.29103Z","caller":"traceutil/trace.go:171","msg":"trace[313345605] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"236.905608ms","start":"2026-10-17T10:56:18.054083Z","end":"2026-10-17T10:56:18.290989Z","steps":["trace[313345605] 'process raft request' (duration: 195.511936ms)","trace[313345605] 'compare' (duration: 41.149584ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:56:18.291092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.403884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T10:56:18.291126Z","caller":"traceutil/trace.go:171","msg":"trace[526665376] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1174; }","duration":"208.500357ms","start":"2026-10-17T10:56:18.082615Z","end":"2026-10-17T10:56:18.291116Z","steps":["trace[526665376] 'agreement among raft nodes before linearized reading' (duration: 208.411074ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:56:38.63586Z","caller":"traceutil/trace.go:171","msg":"trace[998441668] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"168.677121ms","start":"2026-10-17T10:56:38.467153Z","end":"2026-10-17T10:56:38.63583Z","steps":["trace[998441668] 'process raft request' (duration: 168.477705ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:56:38.651624Z","caller":"traceutil/trace.go:171","msg":"trace[666461685] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"159.310262ms","start":"2026-10-17T10:56:38.492292Z","end":"2026-10-17T10:56:38.651602Z","steps":["trace[666461685] 'process raft request' (duration: 159.211269ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:04.283877Z","caller":"traceutil/trace.go:171","msg":"trace[1640953796] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"168.250958ms","start":"2026-10-17T10:57:04.115595Z","end":"2026-10-17T10:57:04.283846Z","steps":["trace[1640953796] 'process raft request' (duration: 168.038831ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:04.469504Z","caller":"traceutil/trace.go:171","msg":"trace[1216837604] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"181.213703ms","start":"2026-10-17T10:57:04.288262Z","end":"2026-10-17T10:57:04.469476Z","steps":["trace[1216837604] 'process raft request' (duration: 181.021947ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:04.469856Z","caller":"traceutil/trace.go:171","msg":"trace[417564358] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"181.543843ms","start":"2026-10-17T10:57:04.288282Z","end":"2026-10-17T10:57:04.469826Z","steps":["trace[417564358] 'process raft request' (duration: 181.153701ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:04.469941Z","caller":"traceutil/trace.go:171","msg":"trace[1648240026] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"181.345086ms","start":"2026-10-17T10:57:04.288578Z","end":"2026-10-17T10:57:04.469923Z","steps":["trace[1648240026] 'process raft request' (duration: 181.1481ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:04.470009Z","caller":"traceutil/trace.go:171","msg":"trace[1039332379] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"181.352026ms","start":"2026-10-17T10:57:04.288625Z","end":"2026-10-17T10:57:04.469977Z","steps":["trace[1039332379] 'process raft request' (duration: 181.261233ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:04.470015Z","caller":"traceutil/trace.go:171","msg":"trace[362520360] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"181.351336ms","start":"2026-10-17T10:57:04.288632Z","end":"2026-10-17T10:57:04.469983Z","steps":["trace[362520360] 'process raft request' (duration: 181.188331ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:04.911251Z","caller":"traceutil/trace.go:171","msg":"trace[1264387041] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"165.228141ms","start":"2026-10-17T10:57:04.745989Z","end":"2026-10-17T10:57:04.911217Z","steps":["trace[1264387041] 'process raft request' (duration: 164.081013ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:57:04.911744Z","caller":"traceutil/trace.go:171","msg":"trace[189578837] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"165.127757ms","start":"2026-10-17T10:57:04.746587Z","end":"2026-10-17T10:57:04.911715Z","steps":["trace[189578837] 'process raft request' (duration: 165.015424ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:57:49.888973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.491168ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-17T10:57:49.889142Z","caller":"traceutil/trace.go:171","msg":"trace[55336651] linearizableReadLoop","detail":"{readStateIndex:2232; appliedIndex:2231; }","duration":"167.179736ms","start":"2026-10-17T10:57:49.72194Z","end":"2026-10-17T10:57:49.889119Z","steps":["trace[55336651] 'read index received' (duration: 64.531973ms)","trace[55336651] 'applied index is now lower than readState.Index' (duration: 102.645943ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:57:49.889374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.417614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-17T10:57:49.889467Z","caller":"traceutil/trace.go:171","msg":"trace[36043607] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2150; }","duration":"167.555558ms","start":"2026-10-17T10:57:49.721888Z","end":"2026-10-17T10:57:49.889444Z","steps":["trace[36043607] 'agreement among raft nodes before linearized reading' (duration: 167.28502ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:58:39.180791Z","caller":"traceutil/trace.go:171","msg":"trace[575345592] linearizableReadLoop","detail":"{readStateIndex:2554; appliedIndex:2553; }","duration":"107.379144ms","start":"2026-10-17T10:58:39.073388Z","end":"2026-10-17T10:58:39.180768Z","steps":["trace[575345592] 'read index received' (duration: 107.118136ms)","trace[575345592] 'applied index is now lower than readState.Index' (duration: 260.128µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:58:39.180978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.566409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:58:39.181031Z","caller":"traceutil/trace.go:171","msg":"trace[462460559] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2462; }","duration":"107.678763ms","start":"2026-10-17T10:58:39.073338Z","end":"2026-10-17T10:58:39.181016Z","steps":["trace[462460559] 'agreement among raft nodes before linearized reading' (duration: 107.543689ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:58:39.181437Z","caller":"traceutil/trace.go:171","msg":"trace[679931668] transaction","detail":"{read_only:false; response_revision:2462; number_of_response:1; }","duration":"142.048486ms","start":"2026-10-17T10:58:39.039345Z","end":"2026-10-17T10:58:39.181394Z","steps":["trace[679931668] 'process raft request' (duration: 141.24264ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:58:39.182022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.944836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-17T10:58:39.182076Z","caller":"traceutil/trace.go:171","msg":"trace[1181634723] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2462; }","duration":"105.018178ms","start":"2026-10-17T10:58:39.077041Z","end":"2026-10-17T10:58:39.182059Z","steps":["trace[1181634723] 'agreement among raft nodes before linearized reading' (duration: 104.886334ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:59:13.238061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.421319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T10:59:13.23809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.502465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:59:13.238188Z","caller":"traceutil/trace.go:171","msg":"trace[2040345392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2651; }","duration":"140.641286ms","start":"2026-10-17T10:59:13.097519Z","end":"2026-10-17T10:59:13.238161Z","steps":["trace[2040345392] 'range keys from in-memory index tree' (duration: 140.293755ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:59:13.23821Z","caller":"traceutil/trace.go:171","msg":"trace[1876199081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2651; }","duration":"172.679971ms","start":"2026-10-17T10:59:13.065507Z","end":"2026-10-17T10:59:13.238187Z","steps":["trace[1876199081] 'range keys from in-memory index tree' (duration: 172.411103ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:59:13.824425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.282581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T10:59:13.824809Z","caller":"traceutil/trace.go:171","msg":"trace[716848912] transaction","detail":"{read_only:false; response_revision:2654; number_of_response:1; }","duration":"374.658147ms","start":"2026-10-17T10:59:13.450012Z","end":"2026-10-17T10:59:13.82467Z","steps":["trace[716848912] 'process raft request' (duration: 154.046065ms)","trace[716848912] 'compare' (duration: 220.003672ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:59:13.824803Z","caller":"traceutil/trace.go:171","msg":"trace[2106565676] transaction","detail":"{read_only:false; response_revision:2655; number_of_response:1; }","duration":"373.227781ms","start":"2026-10-17T10:59:13.451543Z","end":"2026-10-17T10:59:13.824771Z","steps":["trace[2106565676] 'process raft request' (duration: 372.982043ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:59:13.824911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:59:13.449963Z","time spent":"374.900144ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:59:13.82512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:59:13.451524Z","time spent":"373.353615ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:59:13.825157Z","caller":"traceutil/trace.go:171","msg":"trace[250749697] transaction","detail":"{read_only:false; response_revision:2657; number_of_response:1; }","duration":"311.921241ms","start":"2026-10-17T10:59:13.513197Z","end":"2026-10-17T10:59:13.825118Z","steps":["trace[250749697] 'process raft request' (duration: 311.485847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:59:13.82523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:59:13.513085Z","time spent":"312.104108ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:59:13.825469Z","caller":"traceutil/trace.go:171","msg":"trace[1679774751] transaction","detail":"{read_only:false; response_revision:2656; number_of_response:1; }","duration":"373.853472ms","start":"2026-10-17T10:59:13.451603Z","end":"2026-10-17T10:59:13.825456Z","steps":["trace[1679774751] 'process raft request' (duration: 373.016174ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:59:13.825496Z","caller":"traceutil/trace.go:171","msg":"trace[311731274] linearizableReadLoop","detail":"{readStateIndex:2756; appliedIndex:2752; }","duration":"362.767497ms","start":"2026-10-17T10:59:13.462715Z","end":"2026-10-17T10:59:13.825483Z","steps":["trace[311731274] 'read index received' (duration: 21.188158ms)","trace[311731274] 'applied index is now lower than readState.Index' (duration: 341.571729ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:59:13.825561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:59:13.451586Z","time spent":"373.933345ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:59:13.825678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.954813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T10:59:13.825714Z","caller":"traceutil/trace.go:171","msg":"trace[1446323288] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2658; }","duration":"363.028845ms","start":"2026-10-17T10:59:13.462676Z","end":"2026-10-17T10:59:13.825704Z","steps":["trace[1446323288] 'agreement among raft nodes before linearized reading' (duration: 362.874201ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:59:13.825785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:59:13.462663Z","time spent":"363.089778ms","remote":"127.0.0.1:55750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":23,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-10-17T10:59:13.825834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.054091ms","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-10-17T10:59:13.825893Z","caller":"traceutil/trace.go:171","msg":"trace[391402545] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2658; }","duration":"340.162525ms","start":"2026-10-17T10:59:13.485714Z","end":"2026-10-17T10:59:13.825877Z","steps":["trace[391402545] 'agreement among raft nodes before linearized reading' (duration: 340.063012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:59:13.825936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:59:13.485702Z","time spent":"340.221216ms","remote":"127.0.0.1:56082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-10-17T10:59:13.826124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.724162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T10:59:13.826232Z","caller":"traceutil/trace.go:171","msg":"trace[1727887800] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2658; }","duration":"101.872777ms","start":"2026-10-17T10:59:13.724342Z","end":"2026-10-17T10:59:13.826215Z","steps":["trace[1727887800] 'agreement among raft nodes before linearized reading' (duration: 101.739272ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:02:03.358916Z","caller":"traceutil/trace.go:171","msg":"trace[482242151] transaction","detail":"{read_only:false; response_revision:3668; number_of_response:1; }","duration":"117.32669ms","start":"2026-10-17T11:02:03.241565Z","end":"2026-10-17T11:02:03.358891Z","steps":["trace[482242151] 'process raft request' (duration: 108.807788ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:02:03.359017Z","caller":"traceutil/trace.go:171","msg":"trace[1109722420] transaction","detail":"{read_only:false; response_revision:3669; number_of_response:1; }","duration":"113.740236ms","start":"2026-10-17T11:02:03.245251Z","end":"2026-10-17T11:02:03.358991Z","steps":["trace[1109722420] 'process raft request' (duration: 113.569061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:02:36.811067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.125872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:02:36.81143Z","caller":"traceutil/trace.go:171","msg":"trace[301361871] transaction","detail":"{read_only:false; response_revision:3900; number_of_response:1; }","duration":"245.247418ms","start":"2026-10-17T11:02:36.566159Z","end":"2026-10-17T11:02:36.811406Z","steps":["trace[301361871] 'process raft request' (duration: 245.189706ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:02:36.811459Z","caller":"traceutil/trace.go:171","msg":"trace[549446515] transaction","detail":"{read_only:false; response_revision:3898; number_of_response:1; }","duration":"311.282729ms","start":"2026-10-17T11:02:36.500114Z","end":"2026-10-17T11:02:36.811397Z","steps":["trace[549446515] 'process raft request' (duration: 199.753175ms)","trace[549446515] 'compare' (duration: 110.990027ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:02:36.811463Z","caller":"traceutil/trace.go:171","msg":"trace[717901098] transaction","detail":"{read_only:false; response_revision:3899; number_of_response:1; }","duration":"246.747146ms","start":"2026-10-17T11:02:36.564676Z","end":"2026-10-17T11:02:36.811423Z","steps":["trace[717901098] 'process raft request' (duration: 246.600052ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:02:36.811773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:02:36.500086Z","time spent":"311.487825ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:03:58.839558Z","caller":"traceutil/trace.go:171","msg":"trace[1141443164] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"115.699885ms","start":"2026-10-17T11:03:58.723832Z","end":"2026-10-17T11:03:58.839532Z","steps":["trace[1141443164] 'process raft request' (duration: 97.432091ms)","trace[1141443164] 'compare' (duration: 18.152919ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:03:58.841381Z","caller":"traceutil/trace.go:171","msg":"trace[884149913] transaction","detail":"{read_only:false; response_revision:4893; number_of_response:1; }","duration":"109.233018ms","start":"2026-10-17T11:03:58.732107Z","end":"2026-10-17T11:03:58.84134Z","steps":["trace[884149913] 'process raft request' (duration: 109.080493ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:03:58.841486Z","caller":"traceutil/trace.go:171","msg":"trace[585510581] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"116.614593ms","start":"2026-10-17T11:03:58.724799Z","end":"2026-10-17T11:03:58.841414Z","steps":["trace[585510581] 'process raft request' (duration: 116.223101ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:02.73036Z","caller":"traceutil/trace.go:171","msg":"trace[1044143866] linearizableReadLoop","detail":"{readStateIndex:5245; appliedIndex:5244; }","duration":"116.661064ms","start":"2026-10-17T11:04:02.613665Z","end":"2026-10-17T11:04:02.730326Z","steps":["trace[1044143866] 'read index received' (duration: 116.438987ms)","trace[1044143866] 'applied index is now lower than readState.Index' (duration: 220.537µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:04:02.730546Z","caller":"traceutil/trace.go:171","msg":"trace[160219627] transaction","detail":"{read_only:false; response_revision:5054; number_of_response:1; }","duration":"154.157542ms","start":"2026-10-17T11:04:02.576355Z","end":"2026-10-17T11:04:02.730513Z","steps":["trace[160219627] 'process raft request' (duration: 153.78101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:04:02.730668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.976615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-17T11:04:02.730761Z","caller":"traceutil/trace.go:171","msg":"trace[1748637687] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5054; }","duration":"117.1245ms","start":"2026-10-17T11:04:02.613616Z","end":"2026-10-17T11:04:02.73074Z","steps":["trace[1748637687] 'agreement among raft nodes before linearized reading' (duration: 116.902362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:04:02.73102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.212753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:763975"} {"level":"info","ts":"2026-10-17T11:04:02.731114Z","caller":"traceutil/trace.go:171","msg":"trace[1630051164] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5054; }","duration":"117.361367ms","start":"2026-10-17T11:04:02.613731Z","end":"2026-10-17T11:04:02.731092Z","steps":["trace[1630051164] 'agreement among raft nodes before linearized reading' (duration: 116.823129ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:04.87894Z","caller":"traceutil/trace.go:171","msg":"trace[1662397792] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"130.397143ms","start":"2026-10-17T11:04:04.748515Z","end":"2026-10-17T11:04:04.878912Z","steps":["trace[1662397792] 'process raft request' (duration: 130.242908ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:05.274736Z","caller":"traceutil/trace.go:171","msg":"trace[66353863] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"164.558765ms","start":"2026-10-17T11:04:05.110134Z","end":"2026-10-17T11:04:05.274692Z","steps":["trace[66353863] 'process raft request' (duration: 129.825455ms)","trace[66353863] 'compare' (duration: 34.605266ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:04:05.385919Z","caller":"traceutil/trace.go:171","msg":"trace[1025558861] linearizableReadLoop","detail":"{readStateIndex:5286; appliedIndex:5284; }","duration":"119.98135ms","start":"2026-10-17T11:04:05.265914Z","end":"2026-10-17T11:04:05.385895Z","steps":["trace[1025558861] 'read index received' (duration: 111.293782ms)","trace[1025558861] 'applied index is now lower than readState.Index' (duration: 8.686638ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:04:05.386598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.668303ms","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-10-17T11:04:05.386645Z","caller":"traceutil/trace.go:171","msg":"trace[1689081411] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"254.580048ms","start":"2026-10-17T11:04:05.132029Z","end":"2026-10-17T11:04:05.386609Z","steps":["trace[1689081411] 'process raft request' (duration: 245.244249ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:05.386696Z","caller":"traceutil/trace.go:171","msg":"trace[470788869] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"254.555438ms","start":"2026-10-17T11:04:05.132113Z","end":"2026-10-17T11:04:05.386668Z","steps":["trace[470788869] 'process raft request' (duration: 253.733591ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:05.386695Z","caller":"traceutil/trace.go:171","msg":"trace[1168018625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5092; }","duration":"120.817167ms","start":"2026-10-17T11:04:05.265858Z","end":"2026-10-17T11:04:05.386675Z","steps":["trace[1168018625] 'agreement among raft nodes before linearized reading' (duration: 120.566739ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:04:05.386774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.25287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-17T11:04:05.386827Z","caller":"traceutil/trace.go:171","msg":"trace[1609272219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5092; }","duration":"115.337043ms","start":"2026-10-17T11:04:05.271477Z","end":"2026-10-17T11:04:05.386814Z","steps":["trace[1609272219] 'agreement among raft nodes before linearized reading' (duration: 115.128236ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:04:05.386989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.669711ms","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-10-17T11:04:05.387022Z","caller":"traceutil/trace.go:171","msg":"trace[804787535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5092; }","duration":"109.733393ms","start":"2026-10-17T11:04:05.27728Z","end":"2026-10-17T11:04:05.387014Z","steps":["trace[804787535] 'agreement among raft nodes before linearized reading' (duration: 109.65041ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:19.155436Z","caller":"traceutil/trace.go:171","msg":"trace[447906390] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"334.982066ms","start":"2026-10-17T11:04:18.820423Z","end":"2026-10-17T11:04:19.155405Z","steps":["trace[447906390] 'process raft request' (duration: 248.744093ms)","trace[447906390] 'compare' (duration: 85.938793ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:04:19.155591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:04:18.820405Z","time spent":"335.1092ms","remote":"127.0.0.1:56104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5085,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:04:19.155572Z","caller":"traceutil/trace.go:171","msg":"trace[1034407969] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"335.043349ms","start":"2026-10-17T11:04:18.820493Z","end":"2026-10-17T11:04:19.155537Z","steps":["trace[1034407969] 'process raft request' (duration: 334.824532ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:19.155709Z","caller":"traceutil/trace.go:171","msg":"trace[856600445] linearizableReadLoop","detail":"{readStateIndex:5538; appliedIndex:5535; }","duration":"259.969241ms","start":"2026-10-17T11:04:18.895701Z","end":"2026-10-17T11:04:19.15567Z","steps":["trace[856600445] 'read index received' (duration: 173.584982ms)","trace[856600445] 'applied index is now lower than readState.Index' (duration: 86.379999ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:04:19.155747Z","caller":"traceutil/trace.go:171","msg":"trace[577589662] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"335.190343ms","start":"2026-10-17T11:04:18.820493Z","end":"2026-10-17T11:04:19.155684Z","steps":["trace[577589662] 'process raft request' (duration: 334.953676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:04:19.155789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:04:18.820468Z","time spent":"335.188592ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8334,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:04:19.155901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:04:18.820469Z","time spent":"335.359768ms","remote":"127.0.0.1:55930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1564,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:04:19.155919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.210159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-10-17T11:04:19.155981Z","caller":"traceutil/trace.go:171","msg":"trace[998923525] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5338; }","duration":"260.313562ms","start":"2026-10-17T11:04:18.895649Z","end":"2026-10-17T11:04:19.155963Z","steps":["trace[998923525] 'agreement among raft nodes before linearized reading' (duration: 260.153427ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:04:19.156013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.34319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-17T11:04:19.156052Z","caller":"traceutil/trace.go:171","msg":"trace[1087488915] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5338; }","duration":"212.421562ms","start":"2026-10-17T11:04:18.943616Z","end":"2026-10-17T11:04:19.156038Z","steps":["trace[1087488915] 'agreement among raft nodes before linearized reading' (duration: 212.236647ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:04:26.110921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.017367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4f7d4397-efd4-4a6e-a36e-8ab6e47e76e6\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-10-17T11:04:26.110992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.085503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T11:04:26.111022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.606663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T11:04:26.111028Z","caller":"traceutil/trace.go:171","msg":"trace[1259834789] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4f7d4397-efd4-4a6e-a36e-8ab6e47e76e6; range_end:; response_count:1; response_revision:5490; }","duration":"107.159892ms","start":"2026-10-17T11:04:26.003844Z","end":"2026-10-17T11:04:26.111004Z","steps":["trace[1259834789] 'range keys from in-memory index tree' (duration: 106.81049ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:26.111049Z","caller":"traceutil/trace.go:171","msg":"trace[1248793482] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:5490; }","duration":"156.210738ms","start":"2026-10-17T11:04:25.954823Z","end":"2026-10-17T11:04:26.111034Z","steps":["trace[1248793482] 'count revisions from in-memory index tree' (duration: 155.97116ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:26.11106Z","caller":"traceutil/trace.go:171","msg":"trace[143648745] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:5490; }","duration":"111.681396ms","start":"2026-10-17T11:04:25.99937Z","end":"2026-10-17T11:04:26.111052Z","steps":["trace[143648745] 'count revisions from in-memory index tree' (duration: 111.49746ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:04:26.112137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.982758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2380603"} {"level":"info","ts":"2026-10-17T11:04:26.112201Z","caller":"traceutil/trace.go:171","msg":"trace[1327424017] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5490; }","duration":"160.05107ms","start":"2026-10-17T11:04:25.952134Z","end":"2026-10-17T11:04:26.112185Z","steps":["trace[1327424017] 'range keys from in-memory index tree' (duration: 159.033039ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:26.354207Z","caller":"traceutil/trace.go:171","msg":"trace[452141589] transaction","detail":"{read_only:false; response_revision:5492; number_of_response:1; }","duration":"239.659011ms","start":"2026-10-17T11:04:26.114518Z","end":"2026-10-17T11:04:26.354177Z","steps":["trace[452141589] 'process raft request' (duration: 168.842621ms)","trace[452141589] 'compare' (duration: 70.402747ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:04:26.354638Z","caller":"traceutil/trace.go:171","msg":"trace[1236282493] linearizableReadLoop","detail":"{readStateIndex:5699; appliedIndex:5697; }","duration":"206.576875ms","start":"2026-10-17T11:04:26.148049Z","end":"2026-10-17T11:04:26.354625Z","steps":["trace[1236282493] 'read index received' (duration: 135.324841ms)","trace[1236282493] 'applied index is now lower than readState.Index' (duration: 71.250974ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:04:26.354755Z","caller":"traceutil/trace.go:171","msg":"trace[469179414] transaction","detail":"{read_only:false; response_revision:5493; number_of_response:1; }","duration":"239.074033ms","start":"2026-10-17T11:04:26.115672Z","end":"2026-10-17T11:04:26.354746Z","steps":["trace[469179414] 'process raft request' (duration: 238.225826ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:04:26.355092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.02562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-10-17T11:04:26.355154Z","caller":"traceutil/trace.go:171","msg":"trace[857338981] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5493; }","duration":"207.084702ms","start":"2026-10-17T11:04:26.148039Z","end":"2026-10-17T11:04:26.355123Z","steps":["trace[857338981] 'agreement among raft nodes before linearized reading' (duration: 206.895016ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:26.566961Z","caller":"traceutil/trace.go:171","msg":"trace[1480684073] transaction","detail":"{read_only:false; response_revision:5494; number_of_response:1; }","duration":"205.895704ms","start":"2026-10-17T11:04:26.361036Z","end":"2026-10-17T11:04:26.566932Z","steps":["trace[1480684073] 'process raft request' (duration: 125.959902ms)","trace[1480684073] 'compare' (duration: 79.522749ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:04:26.567473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.516084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3640"} {"level":"info","ts":"2026-10-17T11:04:26.56753Z","caller":"traceutil/trace.go:171","msg":"trace[1354259839] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5494; }","duration":"180.617367ms","start":"2026-10-17T11:04:26.386899Z","end":"2026-10-17T11:04:26.567517Z","steps":["trace[1354259839] 'agreement among raft nodes before linearized reading' (duration: 180.40938ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:26.567258Z","caller":"traceutil/trace.go:171","msg":"trace[1525973295] linearizableReadLoop","detail":"{readStateIndex:5700; appliedIndex:5699; }","duration":"179.900734ms","start":"2026-10-17T11:04:26.386929Z","end":"2026-10-17T11:04:26.56683Z","steps":["trace[1525973295] 'read index received' (duration: 100.080326ms)","trace[1525973295] 'applied index is now lower than readState.Index' (duration: 79.818888ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:04:26.568017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.080739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:04:26.568135Z","caller":"traceutil/trace.go:171","msg":"trace[963456266] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:5494; }","duration":"174.217153ms","start":"2026-10-17T11:04:26.393902Z","end":"2026-10-17T11:04:26.568119Z","steps":["trace[963456266] 'agreement among raft nodes before linearized reading' (duration: 174.043198ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:39.108447Z","caller":"traceutil/trace.go:171","msg":"trace[1657846902] transaction","detail":"{read_only:false; response_revision:5650; number_of_response:1; }","duration":"112.742199ms","start":"2026-10-17T11:04:38.995684Z","end":"2026-10-17T11:04:39.108427Z","steps":["trace[1657846902] 'process raft request' (duration: 112.554834ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:04:40.793601Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2795} {"level":"info","ts":"2026-10-17T11:04:40.866284Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2795,"took":"71.06266ms","hash":1308719747,"current-db-size-bytes":55681024,"current-db-size":"56 MB","current-db-size-in-use-bytes":31252480,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T11:04:40.866353Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1308719747,"revision":2795,"compact-revision":-1} {"level":"info","ts":"2026-10-17T11:05:15.951503Z","caller":"traceutil/trace.go:171","msg":"trace[716455180] linearizableReadLoop","detail":"{readStateIndex:6213; appliedIndex:6212; }","duration":"116.240991ms","start":"2026-10-17T11:05:15.835241Z","end":"2026-10-17T11:05:15.951482Z","steps":["trace[716455180] 'read index received' (duration: 70.957669ms)","trace[716455180] 'applied index is now lower than readState.Index' (duration: 45.282222ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:05:15.951586Z","caller":"traceutil/trace.go:171","msg":"trace[1247041665] transaction","detail":"{read_only:false; response_revision:5992; number_of_response:1; }","duration":"126.957532ms","start":"2026-10-17T11:05:15.824587Z","end":"2026-10-17T11:05:15.951545Z","steps":["trace[1247041665] 'process raft request' (duration: 81.608218ms)","trace[1247041665] 'compare' (duration: 45.190299ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:05:15.95171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.446297ms","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-10-17T11:05:15.951757Z","caller":"traceutil/trace.go:171","msg":"trace[1406649352] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5992; }","duration":"116.534419ms","start":"2026-10-17T11:05:15.835214Z","end":"2026-10-17T11:05:15.951748Z","steps":["trace[1406649352] 'agreement among raft nodes before linearized reading' (duration: 116.393865ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:05:15.951843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.370297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-17T11:05:15.951946Z","caller":"traceutil/trace.go:171","msg":"trace[996395928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5992; }","duration":"112.51144ms","start":"2026-10-17T11:05:15.839413Z","end":"2026-10-17T11:05:15.951924Z","steps":["trace[996395928] 'agreement among raft nodes before linearized reading' (duration: 112.271013ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:05:16.278141Z","caller":"traceutil/trace.go:171","msg":"trace[1993403676] transaction","detail":"{read_only:false; response_revision:5995; number_of_response:1; }","duration":"120.593668ms","start":"2026-10-17T11:05:16.157522Z","end":"2026-10-17T11:05:16.278116Z","steps":["trace[1993403676] 'process raft request' (duration: 120.432573ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:09:40.799754Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5671} {"level":"info","ts":"2026-10-17T11:09:40.881793Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5671,"took":"80.422499ms","hash":411360082,"current-db-size-bytes":55681024,"current-db-size":"56 MB","current-db-size-in-use-bytes":25456640,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-17T11:09:40.881852Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":411360082,"revision":5671,"compact-revision":2795} {"level":"info","ts":"2026-10-17T11:10:28.644299Z","caller":"traceutil/trace.go:171","msg":"trace[371569558] linearizableReadLoop","detail":"{readStateIndex:8609; appliedIndex:8608; }","duration":"226.399495ms","start":"2026-10-17T11:10:28.417874Z","end":"2026-10-17T11:10:28.644274Z","steps":["trace[371569558] 'read index received' (duration: 226.23976ms)","trace[371569558] 'applied index is now lower than readState.Index' (duration: 158.025µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:10:28.644366Z","caller":"traceutil/trace.go:171","msg":"trace[281782086] transaction","detail":"{read_only:false; response_revision:8313; number_of_response:1; }","duration":"282.036036ms","start":"2026-10-17T11:10:28.362298Z","end":"2026-10-17T11:10:28.644334Z","steps":["trace[281782086] 'process raft request' (duration: 281.802218ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:28.644488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.600122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bkh67\" ","response":"range_response_count:1 size:6518"} {"level":"info","ts":"2026-10-17T11:10:28.644553Z","caller":"traceutil/trace.go:171","msg":"trace[105687806] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bkh67; range_end:; response_count:1; response_revision:8313; }","duration":"226.710255ms","start":"2026-10-17T11:10:28.417823Z","end":"2026-10-17T11:10:28.644533Z","steps":["trace[105687806] 'agreement among raft nodes before linearized reading' (duration: 226.56558ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:28.644557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.554364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-17T11:10:28.644625Z","caller":"traceutil/trace.go:171","msg":"trace[43282809] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8313; }","duration":"124.663576ms","start":"2026-10-17T11:10:28.519948Z","end":"2026-10-17T11:10:28.644611Z","steps":["trace[43282809] 'agreement among raft nodes before linearized reading' (duration: 124.47758ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:28.947171Z","caller":"traceutil/trace.go:171","msg":"trace[858127433] transaction","detail":"{read_only:false; response_revision:8314; number_of_response:1; }","duration":"299.46258ms","start":"2026-10-17T11:10:28.647687Z","end":"2026-10-17T11:10:28.947149Z","steps":["trace[858127433] 'process raft request' (duration: 299.283165ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:28.947482Z","caller":"traceutil/trace.go:171","msg":"trace[1908981230] linearizableReadLoop","detail":"{readStateIndex:8610; appliedIndex:8609; }","duration":"297.691053ms","start":"2026-10-17T11:10:28.649423Z","end":"2026-10-17T11:10:28.947114Z","steps":["trace[1908981230] 'read index received' (duration: 297.518598ms)","trace[1908981230] 'applied index is now lower than readState.Index' (duration: 171.585µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:10:28.947702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.253631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:208195"} {"level":"info","ts":"2026-10-17T11:10:28.947805Z","caller":"traceutil/trace.go:171","msg":"trace[1454878090] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:8314; }","duration":"298.391845ms","start":"2026-10-17T11:10:28.649396Z","end":"2026-10-17T11:10:28.947788Z","steps":["trace[1454878090] 'agreement among raft nodes before linearized reading' (duration: 297.879319ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:28.964684Z","caller":"traceutil/trace.go:171","msg":"trace[247905091] transaction","detail":"{read_only:false; response_revision:8315; number_of_response:1; }","duration":"315.217131ms","start":"2026-10-17T11:10:28.649438Z","end":"2026-10-17T11:10:28.964655Z","steps":["trace[247905091] 'process raft request' (duration: 315.010705ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:28.964915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:10:28.649419Z","time spent":"315.400726ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:10:28.965032Z","caller":"traceutil/trace.go:171","msg":"trace[112303165] transaction","detail":"{read_only:false; response_revision:8316; number_of_response:1; }","duration":"156.058326ms","start":"2026-10-17T11:10:28.808946Z","end":"2026-10-17T11:10:28.965005Z","steps":["trace[112303165] 'process raft request' (duration: 155.660813ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:28.965041Z","caller":"traceutil/trace.go:171","msg":"trace[1410721575] transaction","detail":"{read_only:false; response_revision:8317; number_of_response:1; }","duration":"134.51348ms","start":"2026-10-17T11:10:28.830493Z","end":"2026-10-17T11:10:28.965007Z","steps":["trace[1410721575] 'process raft request' (duration: 134.396046ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:41.296121Z","caller":"traceutil/trace.go:171","msg":"trace[1306690793] transaction","detail":"{read_only:false; response_revision:8374; number_of_response:1; }","duration":"283.473601ms","start":"2026-10-17T11:10:41.012628Z","end":"2026-10-17T11:10:41.296101Z","steps":["trace[1306690793] 'process raft request' (duration: 283.352688ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:41.441008Z","caller":"traceutil/trace.go:171","msg":"trace[1940089180] linearizableReadLoop","detail":"{readStateIndex:8675; appliedIndex:8673; }","duration":"417.995882ms","start":"2026-10-17T11:10:41.022992Z","end":"2026-10-17T11:10:41.440988Z","steps":["trace[1940089180] 'read index received' (duration: 273.064999ms)","trace[1940089180] 'applied index is now lower than readState.Index' (duration: 144.930103ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:10:41.441147Z","caller":"traceutil/trace.go:171","msg":"trace[1822561526] transaction","detail":"{read_only:false; response_revision:8375; number_of_response:1; }","duration":"419.79176ms","start":"2026-10-17T11:10:41.021322Z","end":"2026-10-17T11:10:41.441114Z","steps":["trace[1822561526] 'process raft request' (duration: 410.059741ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:41.441273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.3441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-10-17T11:10:41.441283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.466574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-17T11:10:41.441282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.206012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T11:10:41.441308Z","caller":"traceutil/trace.go:171","msg":"trace[1512344021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8375; }","duration":"241.533736ms","start":"2026-10-17T11:10:41.199768Z","end":"2026-10-17T11:10:41.441301Z","steps":["trace[1512344021] 'agreement among raft nodes before linearized reading' (duration: 241.419913ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:41.441306Z","caller":"traceutil/trace.go:171","msg":"trace[883270381] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8375; }","duration":"214.400942ms","start":"2026-10-17T11:10:41.226895Z","end":"2026-10-17T11:10:41.441296Z","steps":["trace[883270381] 'agreement among raft nodes before linearized reading' (duration: 214.31793ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:41.441341Z","caller":"traceutil/trace.go:171","msg":"trace[65203153] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:8375; }","duration":"206.297335ms","start":"2026-10-17T11:10:41.235026Z","end":"2026-10-17T11:10:41.441324Z","steps":["trace[65203153] 'agreement among raft nodes before linearized reading' (duration: 206.186212ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:41.441342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:10:41.021295Z","time spent":"419.953505ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:10:41.44137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.133961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-17T11:10:41.441468Z","caller":"traceutil/trace.go:171","msg":"trace[816352063] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8375; }","duration":"415.284496ms","start":"2026-10-17T11:10:41.026161Z","end":"2026-10-17T11:10:41.441446Z","steps":["trace[816352063] 'agreement among raft nodes before linearized reading' (duration: 415.021618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:41.441189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.178148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bkh67\" ","response":"range_response_count:1 size:6518"} {"level":"warn","ts":"2026-10-17T11:10:41.441501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.345645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-17T11:10:41.441506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.705583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T11:10:41.441516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:10:41.026143Z","time spent":"415.363838ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-17T11:10:41.441532Z","caller":"traceutil/trace.go:171","msg":"trace[728508289] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:8375; }","duration":"255.422418ms","start":"2026-10-17T11:10:41.186102Z","end":"2026-10-17T11:10:41.441525Z","steps":["trace[728508289] 'agreement among raft nodes before linearized reading' (duration: 255.320215ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:41.441534Z","caller":"traceutil/trace.go:171","msg":"trace[574307044] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8375; }","duration":"384.761224ms","start":"2026-10-17T11:10:41.056765Z","end":"2026-10-17T11:10:41.441526Z","steps":["trace[574307044] 'agreement among raft nodes before linearized reading' (duration: 384.716783ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:41.441536Z","caller":"traceutil/trace.go:171","msg":"trace[1217076247] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bkh67; range_end:; response_count:1; response_revision:8375; }","duration":"418.528349ms","start":"2026-10-17T11:10:41.022985Z","end":"2026-10-17T11:10:41.441514Z","steps":["trace[1217076247] 'agreement among raft nodes before linearized reading' (duration: 418.100896ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:41.441412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.77223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:10:41.441588Z","caller":"traceutil/trace.go:171","msg":"trace[2049499235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8375; }","duration":"343.985357ms","start":"2026-10-17T11:10:41.097598Z","end":"2026-10-17T11:10:41.441583Z","steps":["trace[2049499235] 'agreement among raft nodes before linearized reading' (duration: 343.794781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:41.441613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:10:41.097576Z","time spent":"344.028998ms","remote":"127.0.0.1:55636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-17T11:10:41.441556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:10:41.056721Z","time spent":"384.829946ms","remote":"127.0.0.1:56088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-17T11:10:41.441603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:10:41.022973Z","time spent":"418.613571ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6541,"request content":"key:\"/registry/pods/openstack/keepalived-bkh67\" "} {"level":"warn","ts":"2026-10-17T11:10:41.955452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.181057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:10:41.95571Z","caller":"traceutil/trace.go:171","msg":"trace[827188736] transaction","detail":"{read_only:false; response_revision:8382; number_of_response:1; }","duration":"259.762127ms","start":"2026-10-17T11:10:41.695915Z","end":"2026-10-17T11:10:41.955677Z","steps":["trace[827188736] 'process raft request' (duration: 138.251349ms)","trace[827188736] 'compare' (duration: 120.719322ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:10:41.95589Z","caller":"traceutil/trace.go:171","msg":"trace[1427726884] transaction","detail":"{read_only:false; response_revision:8383; number_of_response:1; }","duration":"258.402714ms","start":"2026-10-17T11:10:41.697298Z","end":"2026-10-17T11:10:41.955701Z","steps":["trace[1427726884] 'process raft request' (duration: 258.26818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:10:42.317788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.083162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:10:42.317867Z","caller":"traceutil/trace.go:171","msg":"trace[19613779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8383; }","duration":"219.172875ms","start":"2026-10-17T11:10:42.098673Z","end":"2026-10-17T11:10:42.317846Z","steps":["trace[19613779] 'range keys from in-memory index tree' (duration: 218.909457ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:10:42.501113Z","caller":"traceutil/trace.go:171","msg":"trace[1235185938] transaction","detail":"{read_only:false; response_revision:8385; number_of_response:1; }","duration":"159.928379ms","start":"2026-10-17T11:10:42.341134Z","end":"2026-10-17T11:10:42.501062Z","steps":["trace[1235185938] 'process raft request' (duration: 96.949486ms)","trace[1235185938] 'compare' (duration: 62.842269ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:11:09.692278Z","caller":"traceutil/trace.go:171","msg":"trace[1820184633] transaction","detail":"{read_only:false; response_revision:8580; number_of_response:1; }","duration":"293.974346ms","start":"2026-10-17T11:11:09.398285Z","end":"2026-10-17T11:11:09.692259Z","steps":["trace[1820184633] 'process raft request' (duration: 293.863332ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:11:10.018685Z","caller":"traceutil/trace.go:171","msg":"trace[260395251] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"466.96856ms","start":"2026-10-17T11:11:09.551685Z","end":"2026-10-17T11:11:10.018654Z","steps":["trace[260395251] 'process raft request' (duration: 448.759901ms)","trace[260395251] 'compare' (duration: 17.826137ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:11:10.018878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:11:09.551661Z","time spent":"467.128406ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:11:10.029214Z","caller":"traceutil/trace.go:171","msg":"trace[1424581444] linearizableReadLoop","detail":"{readStateIndex:8888; appliedIndex:8886; }","duration":"307.527007ms","start":"2026-10-17T11:11:09.721658Z","end":"2026-10-17T11:11:10.029185Z","steps":["trace[1424581444] 'read index received' (duration: 278.72297ms)","trace[1424581444] 'applied index is now lower than readState.Index' (duration: 28.802477ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:11:10.029482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.006403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bkh67\" ","response":"range_response_count:1 size:6518"} {"level":"info","ts":"2026-10-17T11:11:10.029561Z","caller":"traceutil/trace.go:171","msg":"trace[1086110768] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bkh67; range_end:; response_count:1; response_revision:8581; }","duration":"276.117847ms","start":"2026-10-17T11:11:09.753426Z","end":"2026-10-17T11:11:10.029544Z","steps":["trace[1086110768] 'agreement among raft nodes before linearized reading' (duration: 275.956102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:10.029498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.876553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-17T11:11:10.02961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.885408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-17T11:11:10.029642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.269196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-17T11:11:10.029791Z","caller":"traceutil/trace.go:171","msg":"trace[702297570] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8581; }","duration":"289.434361ms","start":"2026-10-17T11:11:09.740338Z","end":"2026-10-17T11:11:10.029772Z","steps":["trace[702297570] 'agreement among raft nodes before linearized reading' (duration: 289.092881ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:10.029664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.501013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-17T11:11:10.029767Z","caller":"traceutil/trace.go:171","msg":"trace[1123803559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8581; }","duration":"308.094965ms","start":"2026-10-17T11:11:09.721627Z","end":"2026-10-17T11:11:10.029722Z","steps":["trace[1123803559] 'agreement among raft nodes before linearized reading' (duration: 307.647791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:10.029945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:11:09.721612Z","time spent":"308.295062ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-17T11:11:10.0299Z","caller":"traceutil/trace.go:171","msg":"trace[224939665] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8581; }","duration":"289.753812ms","start":"2026-10-17T11:11:09.740122Z","end":"2026-10-17T11:11:10.029876Z","steps":["trace[224939665] 'agreement among raft nodes before linearized reading' (duration: 289.3831ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:11:10.029687Z","caller":"traceutil/trace.go:171","msg":"trace[1706797254] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8581; }","duration":"299.105229ms","start":"2026-10-17T11:11:09.730565Z","end":"2026-10-17T11:11:10.02967Z","steps":["trace[1706797254] 'agreement among raft nodes before linearized reading' (duration: 298.776909ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:10.029676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.784719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-17T11:11:10.030089Z","caller":"traceutil/trace.go:171","msg":"trace[545196183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8581; }","duration":"162.199862ms","start":"2026-10-17T11:11:09.867872Z","end":"2026-10-17T11:11:10.030072Z","steps":["trace[545196183] 'agreement among raft nodes before linearized reading' (duration: 161.675525ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:11:23.457133Z","caller":"traceutil/trace.go:171","msg":"trace[824794291] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8692; }","duration":"164.812715ms","start":"2026-10-17T11:11:23.292274Z","end":"2026-10-17T11:11:23.457087Z","steps":["trace[824794291] 'process raft request' (duration: 164.588138ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:11:23.772958Z","caller":"traceutil/trace.go:171","msg":"trace[278058512] linearizableReadLoop","detail":"{readStateIndex:9005; appliedIndex:9003; }","duration":"461.311141ms","start":"2026-10-17T11:11:23.311629Z","end":"2026-10-17T11:11:23.77294Z","steps":["trace[278058512] 'read index received' (duration: 145.326315ms)","trace[278058512] 'applied index is now lower than readState.Index' (duration: 315.984046ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:11:23.773017Z","caller":"traceutil/trace.go:171","msg":"trace[411637512] transaction","detail":"{read_only:false; response_revision:8693; number_of_response:1; }","duration":"478.099326ms","start":"2026-10-17T11:11:23.294878Z","end":"2026-10-17T11:11:23.772977Z","steps":["trace[411637512] 'process raft request' (duration: 438.01048ms)","trace[411637512] 'compare' (duration: 39.5785ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:11:23.773085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.441365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-xx7db\" ","response":"range_response_count:1 size:4792"} {"level":"info","ts":"2026-10-17T11:11:23.773159Z","caller":"traceutil/trace.go:171","msg":"trace[1818410947] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-xx7db; range_end:; response_count:1; response_revision:8694; }","duration":"461.553129ms","start":"2026-10-17T11:11:23.3116Z","end":"2026-10-17T11:11:23.773153Z","steps":["trace[1818410947] 'agreement among raft nodes before linearized reading' (duration: 461.424015ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:11:23.773079Z","caller":"traceutil/trace.go:171","msg":"trace[1779932722] transaction","detail":"{read_only:false; response_revision:8694; number_of_response:1; }","duration":"460.651709ms","start":"2026-10-17T11:11:23.312367Z","end":"2026-10-17T11:11:23.773019Z","steps":["trace[1779932722] 'process raft request' (duration: 460.487454ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:23.773179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:11:23.311583Z","time spent":"461.59184ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4815,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-xx7db\" "} {"level":"warn","ts":"2026-10-17T11:11:23.773212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:11:23.294859Z","time spent":"478.25732ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4777,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:11:23.773285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:11:23.312347Z","time spent":"460.855106ms","remote":"127.0.0.1:55726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-17T11:11:23.835832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.252529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:11:23.835931Z","caller":"traceutil/trace.go:171","msg":"trace[1284334793] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:8695; }","duration":"127.408054ms","start":"2026-10-17T11:11:23.708502Z","end":"2026-10-17T11:11:23.83591Z","steps":["trace[1284334793] 'agreement among raft nodes before linearized reading' (duration: 127.190137ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:23.901667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.877054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-xx7db\" ","response":"range_response_count:1 size:4748"} {"level":"info","ts":"2026-10-17T11:11:23.90176Z","caller":"traceutil/trace.go:171","msg":"trace[1831009985] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-xx7db; range_end:; response_count:1; response_revision:8696; }","duration":"125.018439ms","start":"2026-10-17T11:11:23.776706Z","end":"2026-10-17T11:11:23.901724Z","steps":["trace[1831009985] 'agreement among raft nodes before linearized reading' (duration: 124.901465ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:11:23.901757Z","caller":"traceutil/trace.go:171","msg":"trace[1103211517] transaction","detail":"{read_only:false; response_revision:8696; number_of_response:1; }","duration":"116.043993ms","start":"2026-10-17T11:11:23.785664Z","end":"2026-10-17T11:11:23.901708Z","steps":["trace[1103211517] 'process raft request' (duration: 95.277113ms)","trace[1103211517] 'compare' (duration: 20.570094ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:11:24.305562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.482317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:11:24.305822Z","caller":"traceutil/trace.go:171","msg":"trace[1164053509] transaction","detail":"{read_only:false; response_revision:8698; number_of_response:1; }","duration":"189.863423ms","start":"2026-10-17T11:11:24.115934Z","end":"2026-10-17T11:11:24.305798Z","steps":["trace[1164053509] 'process raft request' (duration: 51.044926ms)","trace[1164053509] 'compare' (duration: 138.373953ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:11:24.305903Z","caller":"traceutil/trace.go:171","msg":"trace[1873136173] linearizableReadLoop","detail":"{readStateIndex:9010; appliedIndex:9009; }","duration":"173.663577ms","start":"2026-10-17T11:11:24.132225Z","end":"2026-10-17T11:11:24.305889Z","steps":["trace[1873136173] 'read index received' (duration: 34.679344ms)","trace[1873136173] 'applied index is now lower than readState.Index' (duration: 138.983033ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:11:24.306059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.828822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-17T11:11:24.306123Z","caller":"traceutil/trace.go:171","msg":"trace[2075780396] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8699; }","duration":"173.946465ms","start":"2026-10-17T11:11:24.132161Z","end":"2026-10-17T11:11:24.306107Z","steps":["trace[2075780396] 'agreement among raft nodes before linearized reading' (duration: 173.800181ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:11:24.639318Z","caller":"traceutil/trace.go:171","msg":"trace[1644472823] linearizableReadLoop","detail":"{readStateIndex:9012; appliedIndex:9011; }","duration":"333.359909ms","start":"2026-10-17T11:11:24.305935Z","end":"2026-10-17T11:11:24.639295Z","steps":["trace[1644472823] 'read index received' (duration: 333.190544ms)","trace[1644472823] 'applied index is now lower than readState.Index' (duration: 167.955µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:11:24.639486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.289931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bkh67\" ","response":"range_response_count:1 size:6518"} {"level":"info","ts":"2026-10-17T11:11:24.639541Z","caller":"traceutil/trace.go:171","msg":"trace[34990359] transaction","detail":"{read_only:false; response_revision:8700; number_of_response:1; }","duration":"386.451518ms","start":"2026-10-17T11:11:24.253079Z","end":"2026-10-17T11:11:24.63953Z","steps":["trace[34990359] 'process raft request' (duration: 386.066136ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:24.639549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.839614ms","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-10-17T11:11:24.639618Z","caller":"traceutil/trace.go:171","msg":"trace[2028065316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8700; }","duration":"492.951508ms","start":"2026-10-17T11:11:24.146649Z","end":"2026-10-17T11:11:24.639601Z","steps":["trace[2028065316] 'agreement among raft nodes before linearized reading' (duration: 492.733861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:24.639613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.538954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-17T11:11:24.639632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.851276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.93\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-10-17T11:11:24.639656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:11:24.146634Z","time spent":"493.01319ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-17T11:11:24.63963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:11:24.253056Z","time spent":"386.516391ms","remote":"127.0.0.1:55888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:11:24.639486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.036812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-10-17T11:11:24.639681Z","caller":"traceutil/trace.go:171","msg":"trace[1466986682] range","detail":"{range_begin:/registry/masterleases/162.253.55.93; range_end:; response_count:1; response_revision:8700; }","duration":"367.984641ms","start":"2026-10-17T11:11:24.271684Z","end":"2026-10-17T11:11:24.639669Z","steps":["trace[1466986682] 'agreement among raft nodes before linearized reading' (duration: 367.852777ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:11:24.639684Z","caller":"traceutil/trace.go:171","msg":"trace[555141127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8700; }","duration":"344.610567ms","start":"2026-10-17T11:11:24.295054Z","end":"2026-10-17T11:11:24.639665Z","steps":["trace[555141127] 'agreement among raft nodes before linearized reading' (duration: 344.40969ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:24.639712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:11:24.271662Z","time spent":"368.043663ms","remote":"127.0.0.1:55668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.93\" "} {"level":"warn","ts":"2026-10-17T11:11:24.639491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.773456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-17T11:11:24.63976Z","caller":"traceutil/trace.go:171","msg":"trace[506401301] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8700; }","duration":"501.086975ms","start":"2026-10-17T11:11:24.138666Z","end":"2026-10-17T11:11:24.639753Z","steps":["trace[506401301] 'agreement among raft nodes before linearized reading' (duration: 500.741425ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:24.639762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:11:24.294995Z","time spent":"344.729061ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-17T11:11:24.639789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:11:24.138647Z","time spent":"501.130307ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-17T11:11:24.639713Z","caller":"traceutil/trace.go:171","msg":"trace[525450813] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8700; }","duration":"500.289411ms","start":"2026-10-17T11:11:24.139406Z","end":"2026-10-17T11:11:24.639695Z","steps":["trace[525450813] 'agreement among raft nodes before linearized reading' (duration: 499.95569ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:24.63988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:11:24.139395Z","time spent":"500.477587ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-10-17T11:11:24.639579Z","caller":"traceutil/trace.go:171","msg":"trace[1060745208] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bkh67; range_end:; response_count:1; response_revision:8700; }","duration":"500.409715ms","start":"2026-10-17T11:11:24.139139Z","end":"2026-10-17T11:11:24.639549Z","steps":["trace[1060745208] 'agreement among raft nodes before linearized reading' (duration: 500.278561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:24.64003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:11:24.139125Z","time spent":"500.896281ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6541,"request content":"key:\"/registry/pods/openstack/keepalived-bkh67\" "} {"level":"warn","ts":"2026-10-17T11:11:24.640082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.844638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T11:11:24.640131Z","caller":"traceutil/trace.go:171","msg":"trace[503407279] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:8700; }","duration":"472.91803ms","start":"2026-10-17T11:11:24.167202Z","end":"2026-10-17T11:11:24.64012Z","steps":["trace[503407279] 'agreement among raft nodes before linearized reading' (duration: 472.124194ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:24.640157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:11:24.167191Z","time spent":"472.959002ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":111,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-10-17T11:11:24.653177Z","caller":"traceutil/trace.go:171","msg":"trace[1687925405] transaction","detail":"{read_only:false; response_revision:8701; number_of_response:1; }","duration":"342.932923ms","start":"2026-10-17T11:11:24.310221Z","end":"2026-10-17T11:11:24.653154Z","steps":["trace[1687925405] 'process raft request' (duration: 342.751947ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:24.653673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:11:24.310193Z","time spent":"343.423929ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:11:24.65352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.417307ms","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-10-17T11:11:24.653939Z","caller":"traceutil/trace.go:171","msg":"trace[1654038106] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8701; }","duration":"315.852081ms","start":"2026-10-17T11:11:24.338055Z","end":"2026-10-17T11:11:24.653907Z","steps":["trace[1654038106] 'agreement among raft nodes before linearized reading' (duration: 315.322294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:24.653572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.908262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:11:24.654093Z","caller":"traceutil/trace.go:171","msg":"trace[1969655235] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:8701; }","duration":"332.45368ms","start":"2026-10-17T11:11:24.321621Z","end":"2026-10-17T11:11:24.654074Z","steps":["trace[1969655235] 'agreement among raft nodes before linearized reading' (duration: 331.923682ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:11:24.654033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:11:24.338041Z","time spent":"315.960796ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-17T11:11:24.654177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:11:24.321603Z","time spent":"332.557924ms","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"info","ts":"2026-10-17T11:11:25.426631Z","caller":"traceutil/trace.go:171","msg":"trace[1996446890] transaction","detail":"{read_only:false; response_revision:8708; number_of_response:1; }","duration":"110.143485ms","start":"2026-10-17T11:11:25.316464Z","end":"2026-10-17T11:11:25.426608Z","steps":["trace[1996446890] 'process raft request' (duration: 110.01286ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:11:36.910414Z","caller":"traceutil/trace.go:171","msg":"trace[859541021] linearizableReadLoop","detail":"{readStateIndex:9123; appliedIndex:9122; }","duration":"101.164879ms","start":"2026-10-17T11:11:36.809227Z","end":"2026-10-17T11:11:36.910392Z","steps":["trace[859541021] 'read index received' (duration: 46.962024ms)","trace[859541021] 'applied index is now lower than readState.Index' (duration: 54.202015ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:11:36.910563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.677724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bkh67\" ","response":"range_response_count:1 size:6518"} {"level":"info","ts":"2026-10-17T11:11:36.910532Z","caller":"traceutil/trace.go:171","msg":"trace[256123211] transaction","detail":"{read_only:false; response_revision:8808; number_of_response:1; }","duration":"116.124526ms","start":"2026-10-17T11:11:36.79436Z","end":"2026-10-17T11:11:36.910485Z","steps":["trace[256123211] 'process raft request' (duration: 61.804977ms)","trace[256123211] 'compare' (duration: 54.128393ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:11:36.910598Z","caller":"traceutil/trace.go:171","msg":"trace[765233816] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bkh67; range_end:; response_count:1; response_revision:8808; }","duration":"111.747196ms","start":"2026-10-17T11:11:36.798839Z","end":"2026-10-17T11:11:36.910586Z","steps":["trace[765233816] 'agreement among raft nodes before linearized reading' (duration: 111.644013ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:12:05.812763Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024b6.wal"} {"level":"info","ts":"2026-10-17T11:12:41.932184Z","caller":"traceutil/trace.go:171","msg":"trace[1500746690] transaction","detail":"{read_only:false; response_revision:9392; number_of_response:1; }","duration":"113.274318ms","start":"2026-10-17T11:12:41.818883Z","end":"2026-10-17T11:12:41.932157Z","steps":["trace[1500746690] 'process raft request' (duration: 113.129294ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:12:42.31497Z","caller":"traceutil/trace.go:171","msg":"trace[459120770] transaction","detail":"{read_only:false; response_revision:9393; number_of_response:1; }","duration":"127.903063ms","start":"2026-10-17T11:12:42.187015Z","end":"2026-10-17T11:12:42.314918Z","steps":["trace[459120770] 'process raft request' (duration: 127.586553ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:13:11.386198Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:13:11.394003Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-17T11:13:11.39411Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-17T11:14:40.806708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7858} {"level":"info","ts":"2026-10-17T11:14:40.875175Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7858,"took":"66.471959ms","hash":2306294447,"current-db-size-bytes":55681024,"current-db-size":"56 MB","current-db-size-in-use-bytes":28459008,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-17T11:14:40.87524Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2306294447,"revision":7858,"compact-revision":5671} {"level":"info","ts":"2026-10-17T11:14:59.650655Z","caller":"traceutil/trace.go:171","msg":"trace[156598436] transaction","detail":"{read_only:false; response_revision:10537; number_of_response:1; }","duration":"120.175412ms","start":"2026-10-17T11:14:59.53045Z","end":"2026-10-17T11:14:59.650625Z","steps":["trace[156598436] 'process raft request' (duration: 119.963536ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:19:40.821552Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10345} {"level":"info","ts":"2026-10-17T11:19:40.905425Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10345,"took":"81.511168ms","hash":1901647191,"current-db-size-bytes":55681024,"current-db-size":"56 MB","current-db-size-in-use-bytes":27103232,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-17T11:19:40.905494Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1901647191,"revision":10345,"compact-revision":7858} {"level":"warn","ts":"2026-10-17T11:22:08.415671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.00937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:22:08.415962Z","caller":"traceutil/trace.go:171","msg":"trace[616762159] transaction","detail":"{read_only:false; response_revision:13688; number_of_response:1; }","duration":"245.93038ms","start":"2026-10-17T11:22:08.170003Z","end":"2026-10-17T11:22:08.415933Z","steps":["trace[616762159] 'process raft request' (duration: 120.529698ms)","trace[616762159] 'compare' (duration: 124.876975ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:22:08.415929Z","caller":"traceutil/trace.go:171","msg":"trace[869511349] linearizableReadLoop","detail":"{readStateIndex:14161; appliedIndex:14160; }","duration":"201.206619ms","start":"2026-10-17T11:22:08.214683Z","end":"2026-10-17T11:22:08.41589Z","steps":["trace[869511349] 'read index received' (duration: 75.676553ms)","trace[869511349] 'applied index is now lower than readState.Index' (duration: 125.526956ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:22:08.416167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.461958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-10-17T11:22:08.416257Z","caller":"traceutil/trace.go:171","msg":"trace[1108350296] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:13689; }","duration":"201.564061ms","start":"2026-10-17T11:22:08.214673Z","end":"2026-10-17T11:22:08.416237Z","steps":["trace[1108350296] 'agreement among raft nodes before linearized reading' (duration: 201.326304ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:48.892501Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10595739885369415644,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-17T11:22:49.134313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.983457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T11:22:49.134423Z","caller":"traceutil/trace.go:171","msg":"trace[1326564230] transaction","detail":"{read_only:false; response_revision:13913; number_of_response:1; }","duration":"754.250119ms","start":"2026-10-17T11:22:48.380153Z","end":"2026-10-17T11:22:49.134403Z","steps":["trace[1326564230] 'process raft request' (duration: 567.086486ms)","trace[1326564230] 'compare' (duration: 186.503433ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:22:49.134479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:22:48.380118Z","time spent":"754.333423ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:22:49.160122Z","caller":"traceutil/trace.go:171","msg":"trace[2031044232] transaction","detail":"{read_only:false; response_revision:13914; number_of_response:1; }","duration":"601.741818ms","start":"2026-10-17T11:22:48.558349Z","end":"2026-10-17T11:22:49.160091Z","steps":["trace[2031044232] 'process raft request' (duration: 601.572753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:49.160282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:22:48.558329Z","time spent":"601.869701ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:22:49.160497Z","caller":"traceutil/trace.go:171","msg":"trace[1706901139] transaction","detail":"{read_only:false; response_revision:13915; number_of_response:1; }","duration":"583.675264ms","start":"2026-10-17T11:22:48.576788Z","end":"2026-10-17T11:22:49.160463Z","steps":["trace[1706901139] 'process raft request' (duration: 583.263421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:49.160672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:22:48.576777Z","time spent":"583.793597ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:22:49.160756Z","caller":"traceutil/trace.go:171","msg":"trace[253191245] linearizableReadLoop","detail":"{readStateIndex:14398; appliedIndex:14394; }","duration":"768.509803ms","start":"2026-10-17T11:22:48.392122Z","end":"2026-10-17T11:22:49.160632Z","steps":["trace[253191245] 'read index received' (duration: 555.132518ms)","trace[253191245] 'applied index is now lower than readState.Index' (duration: 213.375495ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:22:49.160786Z","caller":"traceutil/trace.go:171","msg":"trace[975241646] transaction","detail":"{read_only:false; response_revision:13916; number_of_response:1; }","duration":"441.357134ms","start":"2026-10-17T11:22:48.719369Z","end":"2026-10-17T11:22:49.160726Z","steps":["trace[975241646] 'process raft request' (duration: 441.073205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:49.160933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:22:48.719343Z","time spent":"441.54896ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:22:49.161066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.919806ms","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-10-17T11:22:49.161199Z","caller":"traceutil/trace.go:171","msg":"trace[2055787431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13916; }","duration":"769.131032ms","start":"2026-10-17T11:22:48.392039Z","end":"2026-10-17T11:22:49.16117Z","steps":["trace[2055787431] 'agreement among raft nodes before linearized reading' (duration: 768.808812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:49.161276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:22:48.392017Z","time spent":"769.245856ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-17T11:22:49.162101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.908988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T11:22:49.162189Z","caller":"traceutil/trace.go:171","msg":"trace[1238630142] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:13916; }","duration":"770.063892ms","start":"2026-10-17T11:22:48.392104Z","end":"2026-10-17T11:22:49.162168Z","steps":["trace[1238630142] 'agreement among raft nodes before linearized reading' (duration: 769.891187ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:49.1622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.720653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:22:49.162249Z","caller":"traceutil/trace.go:171","msg":"trace[1203515115] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:13916; }","duration":"724.813696ms","start":"2026-10-17T11:22:48.437421Z","end":"2026-10-17T11:22:49.162235Z","steps":["trace[1203515115] 'agreement among raft nodes before linearized reading' (duration: 724.722144ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:49.162306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:22:48.437399Z","time spent":"724.897338ms","remote":"127.0.0.1:51378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-17T11:22:49.162234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:22:48.392082Z","time spent":"770.142445ms","remote":"127.0.0.1:47938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"warn","ts":"2026-10-17T11:22:49.162125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.441298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/openstack/ovn-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T11:22:49.162396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.965905ms","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-10-17T11:22:49.1624Z","caller":"traceutil/trace.go:171","msg":"trace[1272299589] range","detail":"{range_begin:/registry/daemonsets/openstack/ovn-controller; range_end:; response_count:0; response_revision:13916; }","duration":"287.779978ms","start":"2026-10-17T11:22:48.874584Z","end":"2026-10-17T11:22:49.162364Z","steps":["trace[1272299589] 'agreement among raft nodes before linearized reading' (duration: 287.50274ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:22:49.162475Z","caller":"traceutil/trace.go:171","msg":"trace[393849590] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13916; }","duration":"522.044917ms","start":"2026-10-17T11:22:48.640408Z","end":"2026-10-17T11:22:49.162453Z","steps":["trace[393849590] 'agreement among raft nodes before linearized reading' (duration: 521.80595ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:49.162254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.327807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-10-17T11:22:49.16253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:22:48.640355Z","time spent":"522.162021ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-17T11:22:49.162545Z","caller":"traceutil/trace.go:171","msg":"trace[1072692704] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13916; }","duration":"762.638636ms","start":"2026-10-17T11:22:48.399883Z","end":"2026-10-17T11:22:49.162522Z","steps":["trace[1072692704] 'agreement among raft nodes before linearized reading' (duration: 762.281705ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:49.162641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:22:48.399865Z","time spent":"762.72432ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-17T11:22:49.163177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.315282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-17T11:22:49.163426Z","caller":"traceutil/trace.go:171","msg":"trace[268015458] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:13916; }","duration":"503.5587ms","start":"2026-10-17T11:22:48.659845Z","end":"2026-10-17T11:22:49.163404Z","steps":["trace[268015458] 'agreement among raft nodes before linearized reading' (duration: 502.60011ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:22:49.163485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:22:48.659804Z","time spent":"503.666753ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-10-17T11:23:00.411245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.869936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T11:23:00.411341Z","caller":"traceutil/trace.go:171","msg":"trace[9056097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14089; }","duration":"314.027042ms","start":"2026-10-17T11:23:00.097295Z","end":"2026-10-17T11:23:00.411322Z","steps":["trace[9056097] 'range keys from in-memory index tree' (duration: 313.735693ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:23:00.411324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.168087ms","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":"warn","ts":"2026-10-17T11:23:00.411382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:23:00.097273Z","time spent":"314.101684ms","remote":"127.0.0.1:55636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-17T11:23:00.411436Z","caller":"traceutil/trace.go:171","msg":"trace[1671978633] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:14089; }","duration":"252.331483ms","start":"2026-10-17T11:23:00.159071Z","end":"2026-10-17T11:23:00.411403Z","steps":["trace[1671978633] 'count revisions from in-memory index tree' (duration: 252.102316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:23:00.411672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.009738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bkh67\" ","response":"range_response_count:1 size:6518"} {"level":"info","ts":"2026-10-17T11:23:00.411776Z","caller":"traceutil/trace.go:171","msg":"trace[2133237231] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bkh67; range_end:; response_count:1; response_revision:14089; }","duration":"187.189253ms","start":"2026-10-17T11:23:00.224561Z","end":"2026-10-17T11:23:00.41175Z","steps":["trace[2133237231] 'range keys from in-memory index tree' (duration: 186.859813ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:23:00.635543Z","caller":"traceutil/trace.go:171","msg":"trace[1194711071] linearizableReadLoop","detail":"{readStateIndex:14576; appliedIndex:14575; }","duration":"205.313859ms","start":"2026-10-17T11:23:00.430204Z","end":"2026-10-17T11:23:00.635518Z","steps":["trace[1194711071] 'read index received' (duration: 205.084772ms)","trace[1194711071] 'applied index is now lower than readState.Index' (duration: 228.327µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:23:00.635807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.580678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-72996432-6513-45cf-ac8a-6af4fcf38091\" ","response":"range_response_count:1 size:3606"} {"level":"warn","ts":"2026-10-17T11:23:00.635874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.40669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-17T11:23:00.635907Z","caller":"traceutil/trace.go:171","msg":"trace[1438933134] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-72996432-6513-45cf-ac8a-6af4fcf38091; range_end:; response_count:1; response_revision:14090; }","duration":"205.738843ms","start":"2026-10-17T11:23:00.430153Z","end":"2026-10-17T11:23:00.635892Z","steps":["trace[1438933134] 'agreement among raft nodes before linearized reading' (duration: 205.521936ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:23:00.635932Z","caller":"traceutil/trace.go:171","msg":"trace[1872035444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14090; }","duration":"175.507363ms","start":"2026-10-17T11:23:00.460412Z","end":"2026-10-17T11:23:00.63592Z","steps":["trace[1872035444] 'agreement among raft nodes before linearized reading' (duration: 175.374158ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:23:00.63612Z","caller":"traceutil/trace.go:171","msg":"trace[255904155] transaction","detail":"{read_only:false; response_revision:14090; number_of_response:1; }","duration":"219.233371ms","start":"2026-10-17T11:23:00.416616Z","end":"2026-10-17T11:23:00.63585Z","steps":["trace[255904155] 'process raft request' (duration: 218.743796ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:39.76752Z","caller":"traceutil/trace.go:171","msg":"trace[703438640] transaction","detail":"{read_only:false; response_revision:15171; number_of_response:1; }","duration":"149.031803ms","start":"2026-10-17T11:24:39.618453Z","end":"2026-10-17T11:24:39.767485Z","steps":["trace[703438640] 'process raft request' (duration: 69.797217ms)","trace[703438640] 'compare' (duration: 78.74289ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:24:40.047128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.711902ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-17T11:24:40.047224Z","caller":"traceutil/trace.go:171","msg":"trace[1975433844] linearizableReadLoop","detail":"{readStateIndex:15692; appliedIndex:15690; }","duration":"384.532961ms","start":"2026-10-17T11:24:39.662672Z","end":"2026-10-17T11:24:40.047205Z","steps":["trace[1975433844] 'read index received' (duration: 25.514641ms)","trace[1975433844] 'applied index is now lower than readState.Index' (duration: 359.01647ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:24:40.047431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.741408ms","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-10-17T11:24:40.047508Z","caller":"traceutil/trace.go:171","msg":"trace[1293673925] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:15171; }","duration":"384.871372ms","start":"2026-10-17T11:24:39.662618Z","end":"2026-10-17T11:24:40.04749Z","steps":["trace[1293673925] 'agreement among raft nodes before linearized reading' (duration: 384.719577ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:24:40.047537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:24:39.662598Z","time spent":"384.932184ms","remote":"127.0.0.1:33144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true "} {"level":"warn","ts":"2026-10-17T11:24:40.047572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.151847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T11:24:40.047563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.037879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T11:24:40.047614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.925355ms","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-10-17T11:24:40.047663Z","caller":"traceutil/trace.go:171","msg":"trace[635593300] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15171; }","duration":"239.140713ms","start":"2026-10-17T11:24:39.808503Z","end":"2026-10-17T11:24:40.047643Z","steps":["trace[635593300] 'agreement among raft nodes before linearized reading' (duration: 239.004938ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:24:40.047671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.35045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-17T11:24:40.047686Z","caller":"traceutil/trace.go:171","msg":"trace[1775710437] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15171; }","duration":"238.050078ms","start":"2026-10-17T11:24:39.809617Z","end":"2026-10-17T11:24:40.047667Z","steps":["trace[1775710437] 'agreement among raft nodes before linearized reading' (duration: 237.871033ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:40.04771Z","caller":"traceutil/trace.go:171","msg":"trace[1498688442] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15171; }","duration":"239.404402ms","start":"2026-10-17T11:24:39.808295Z","end":"2026-10-17T11:24:40.047699Z","steps":["trace[1498688442] 'agreement among raft nodes before linearized reading' (duration: 239.152155ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:24:40.047729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.690779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-17T11:24:40.04764Z","caller":"traceutil/trace.go:171","msg":"trace[32243316] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15171; }","duration":"241.23596ms","start":"2026-10-17T11:24:39.806389Z","end":"2026-10-17T11:24:40.047625Z","steps":["trace[32243316] 'agreement among raft nodes before linearized reading' (duration: 241.138007ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:40.047756Z","caller":"traceutil/trace.go:171","msg":"trace[2047462996] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:15171; }","duration":"169.7177ms","start":"2026-10-17T11:24:39.878029Z","end":"2026-10-17T11:24:40.047747Z","steps":["trace[2047462996] 'agreement among raft nodes before linearized reading' (duration: 169.481353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:24:40.047574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.217348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-10-17T11:24:40.047783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.90271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-17T11:24:40.047824Z","caller":"traceutil/trace.go:171","msg":"trace[295576027] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15171; }","duration":"217.489326ms","start":"2026-10-17T11:24:39.830323Z","end":"2026-10-17T11:24:40.047812Z","steps":["trace[295576027] 'agreement among raft nodes before linearized reading' (duration: 217.127035ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:40.047835Z","caller":"traceutil/trace.go:171","msg":"trace[1332554047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15171; }","duration":"297.997152ms","start":"2026-10-17T11:24:39.749823Z","end":"2026-10-17T11:24:40.04782Z","steps":["trace[1332554047] 'agreement among raft nodes before linearized reading' (duration: 297.629711ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:24:40.047798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.52564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"warn","ts":"2026-10-17T11:24:40.047984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.580432ms","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-10-17T11:24:40.047966Z","caller":"traceutil/trace.go:171","msg":"trace[542453882] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15171; }","duration":"241.732536ms","start":"2026-10-17T11:24:39.806208Z","end":"2026-10-17T11:24:40.04794Z","steps":["trace[542453882] 'agreement among raft nodes before linearized reading' (duration: 241.298172ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:40.048029Z","caller":"traceutil/trace.go:171","msg":"trace[1604947728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15171; }","duration":"287.664554ms","start":"2026-10-17T11:24:39.76035Z","end":"2026-10-17T11:24:40.048015Z","steps":["trace[1604947728] 'agreement among raft nodes before linearized reading' (duration: 287.19321ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:24:40.048008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.915347ms","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-10-17T11:24:40.051158Z","caller":"traceutil/trace.go:171","msg":"trace[866574070] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15171; }","duration":"279.026121ms","start":"2026-10-17T11:24:39.769036Z","end":"2026-10-17T11:24:40.048062Z","steps":["trace[866574070] 'agreement among raft nodes before linearized reading' (duration: 278.501984ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:40.414259Z","caller":"traceutil/trace.go:171","msg":"trace[2136241188] transaction","detail":"{read_only:false; response_revision:15173; number_of_response:1; }","duration":"360.094675ms","start":"2026-10-17T11:24:40.054128Z","end":"2026-10-17T11:24:40.414223Z","steps":["trace[2136241188] 'process raft request' (duration: 359.816926ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:40.4143Z","caller":"traceutil/trace.go:171","msg":"trace[876843766] transaction","detail":"{read_only:false; response_revision:15175; number_of_response:1; }","duration":"355.020154ms","start":"2026-10-17T11:24:40.059263Z","end":"2026-10-17T11:24:40.414284Z","steps":["trace[876843766] 'process raft request' (duration: 354.871839ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:40.414328Z","caller":"traceutil/trace.go:171","msg":"trace[1259919677] transaction","detail":"{read_only:false; response_revision:15176; number_of_response:1; }","duration":"353.750342ms","start":"2026-10-17T11:24:40.06057Z","end":"2026-10-17T11:24:40.41432Z","steps":["trace[1259919677] 'process raft request' (duration: 353.598957ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:40.41427Z","caller":"traceutil/trace.go:171","msg":"trace[1508007536] transaction","detail":"{read_only:false; response_revision:15174; number_of_response:1; }","duration":"359.236237ms","start":"2026-10-17T11:24:40.055011Z","end":"2026-10-17T11:24:40.414247Z","steps":["trace[1508007536] 'process raft request' (duration: 359.085012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:24:40.414423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:24:40.060541Z","time spent":"353.815064ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:24:40.414421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:24:40.054099Z","time spent":"360.236709ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:24:40.414425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:24:40.059239Z","time spent":"355.117246ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:24:40.414321Z","caller":"traceutil/trace.go:171","msg":"trace[1750692316] linearizableReadLoop","detail":"{readStateIndex:15697; appliedIndex:15693; }","duration":"247.216989ms","start":"2026-10-17T11:24:40.167086Z","end":"2026-10-17T11:24:40.414303Z","steps":["trace[1750692316] 'read index received' (duration: 246.784136ms)","trace[1750692316] 'applied index is now lower than readState.Index' (duration: 430.983µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:24:40.41446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.367574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-17T11:24:40.414504Z","caller":"traceutil/trace.go:171","msg":"trace[1353253740] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15176; }","duration":"247.452527ms","start":"2026-10-17T11:24:40.167042Z","end":"2026-10-17T11:24:40.414494Z","steps":["trace[1353253740] 'agreement among raft nodes before linearized reading' (duration: 247.310433ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:24:40.414526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:24:40.054996Z","time spent":"359.454294ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:24:40.414651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.89821ms","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-10-17T11:24:40.414724Z","caller":"traceutil/trace.go:171","msg":"trace[1999381719] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15176; }","duration":"246.992403ms","start":"2026-10-17T11:24:40.167712Z","end":"2026-10-17T11:24:40.414705Z","steps":["trace[1999381719] 'agreement among raft nodes before linearized reading' (duration: 246.855299ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:24:40.414802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.229859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"warn","ts":"2026-10-17T11:24:40.414848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.195509ms","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-10-17T11:24:40.41489Z","caller":"traceutil/trace.go:171","msg":"trace[1294037691] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15176; }","duration":"127.360323ms","start":"2026-10-17T11:24:40.287507Z","end":"2026-10-17T11:24:40.414868Z","steps":["trace[1294037691] 'agreement among raft nodes before linearized reading' (duration: 127.150757ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:40.414897Z","caller":"traceutil/trace.go:171","msg":"trace[1725435066] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15176; }","duration":"241.265651ms","start":"2026-10-17T11:24:40.173617Z","end":"2026-10-17T11:24:40.414882Z","steps":["trace[1725435066] 'agreement among raft nodes before linearized reading' (duration: 241.171208ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:24:40.830268Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12606} {"level":"info","ts":"2026-10-17T11:24:40.913208Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12606,"took":"78.72917ms","hash":3668369594,"current-db-size-bytes":55681024,"current-db-size":"56 MB","current-db-size-in-use-bytes":30609408,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T11:24:40.913289Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3668369594,"revision":12606,"compact-revision":10345} {"level":"info","ts":"2026-10-17T11:29:40.844254Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15178} {"level":"info","ts":"2026-10-17T11:29:40.947465Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15178,"took":"100.087765ms","hash":2068465964,"current-db-size-bytes":55681024,"current-db-size":"56 MB","current-db-size-in-use-bytes":31002624,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T11:29:40.947576Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2068465964,"revision":15178,"compact-revision":12606} {"level":"warn","ts":"2026-10-17T11:30:21.465638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.592938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-qg2zq\" ","response":"range_response_count:1 size:22121"} {"level":"info","ts":"2026-10-17T11:30:21.46573Z","caller":"traceutil/trace.go:171","msg":"trace[115196055] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-qg2zq; range_end:; response_count:1; response_revision:17738; }","duration":"134.769383ms","start":"2026-10-17T11:30:21.330941Z","end":"2026-10-17T11:30:21.46571Z","steps":["trace[115196055] 'range keys from in-memory index tree' (duration: 134.321389ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:30:22.24936Z","caller":"traceutil/trace.go:171","msg":"trace[359025451] linearizableReadLoop","detail":"{readStateIndex:18345; appliedIndex:18344; }","duration":"153.62344ms","start":"2026-10-17T11:30:22.095713Z","end":"2026-10-17T11:30:22.249336Z","steps":["trace[359025451] 'read index received' (duration: 153.30871ms)","trace[359025451] 'applied index is now lower than readState.Index' (duration: 313.8µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:30:22.249467Z","caller":"traceutil/trace.go:171","msg":"trace[640968733] transaction","detail":"{read_only:false; response_revision:17740; number_of_response:1; }","duration":"269.757502ms","start":"2026-10-17T11:30:21.979686Z","end":"2026-10-17T11:30:22.249443Z","steps":["trace[640968733] 'process raft request' (duration: 269.479124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:30:22.249528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.758924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:30:22.249577Z","caller":"traceutil/trace.go:171","msg":"trace[1348710521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17740; }","duration":"153.888848ms","start":"2026-10-17T11:30:22.095671Z","end":"2026-10-17T11:30:22.249559Z","steps":["trace[1348710521] 'agreement among raft nodes before linearized reading' (duration: 153.764094ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:30:22.433647Z","caller":"traceutil/trace.go:171","msg":"trace[1585281949] transaction","detail":"{read_only:false; response_revision:17741; number_of_response:1; }","duration":"163.073489ms","start":"2026-10-17T11:30:22.270466Z","end":"2026-10-17T11:30:22.433539Z","steps":["trace[1585281949] 'process raft request' (duration: 138.390368ms)","trace[1585281949] 'compare' (duration: 24.480813ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:30:22.445467Z","caller":"traceutil/trace.go:171","msg":"trace[1237123093] linearizableReadLoop","detail":"{readStateIndex:18347; appliedIndex:18345; }","duration":"134.079752ms","start":"2026-10-17T11:30:22.311356Z","end":"2026-10-17T11:30:22.445436Z","steps":["trace[1237123093] 'read index received' (duration: 97.502285ms)","trace[1237123093] 'applied index is now lower than readState.Index' (duration: 36.576457ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:30:22.445697Z","caller":"traceutil/trace.go:171","msg":"trace[1847837316] transaction","detail":"{read_only:false; response_revision:17742; number_of_response:1; }","duration":"172.419935ms","start":"2026-10-17T11:30:22.273238Z","end":"2026-10-17T11:30:22.445658Z","steps":["trace[1847837316] 'process raft request' (duration: 171.931239ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:30:22.445903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.526605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-10-17T11:30:22.446Z","caller":"traceutil/trace.go:171","msg":"trace[453473590] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:17742; }","duration":"134.658499ms","start":"2026-10-17T11:30:22.311315Z","end":"2026-10-17T11:30:22.445973Z","steps":["trace[453473590] 'agreement among raft nodes before linearized reading' (duration: 134.397261ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:30:40.921579Z","caller":"traceutil/trace.go:171","msg":"trace[1731244252] linearizableReadLoop","detail":"{readStateIndex:18691; appliedIndex:18690; }","duration":"124.525929ms","start":"2026-10-17T11:30:40.797026Z","end":"2026-10-17T11:30:40.921552Z","steps":["trace[1731244252] 'read index received' (duration: 124.285351ms)","trace[1731244252] 'applied index is now lower than readState.Index' (duration: 238.878µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:30:40.921678Z","caller":"traceutil/trace.go:171","msg":"trace[1555893543] transaction","detail":"{read_only:false; response_revision:18074; number_of_response:1; }","duration":"124.759286ms","start":"2026-10-17T11:30:40.796881Z","end":"2026-10-17T11:30:40.921641Z","steps":["trace[1555893543] 'process raft request' (duration: 124.433986ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:30:40.921764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.712545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:30:40.921815Z","caller":"traceutil/trace.go:171","msg":"trace[605557652] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-endpoints; range_end:; response_count:0; response_revision:18074; }","duration":"124.816478ms","start":"2026-10-17T11:30:40.796986Z","end":"2026-10-17T11:30:40.921802Z","steps":["trace[605557652] 'agreement among raft nodes before linearized reading' (duration: 124.722225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:30:40.921853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.452897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-17T11:30:40.921898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.645722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:30:40.921937Z","caller":"traceutil/trace.go:171","msg":"trace[967486917] range","detail":"{range_begin:/registry/jobs/openstack/senlin-rabbit-init; range_end:; response_count:0; response_revision:18074; }","duration":"124.548819ms","start":"2026-10-17T11:30:40.797369Z","end":"2026-10-17T11:30:40.921918Z","steps":["trace[967486917] 'agreement among raft nodes before linearized reading' (duration: 124.417685ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:30:40.921962Z","caller":"traceutil/trace.go:171","msg":"trace[933234035] range","detail":"{range_begin:/registry/jobs/openstack/senlin-ks-user; range_end:; response_count:0; response_revision:18074; }","duration":"124.746335ms","start":"2026-10-17T11:30:40.797193Z","end":"2026-10-17T11:30:40.921939Z","steps":["trace[933234035] 'agreement among raft nodes before linearized reading' (duration: 124.633001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:30:40.921846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.608131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/senlin-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:30:40.922074Z","caller":"traceutil/trace.go:171","msg":"trace[1932921129] range","detail":"{range_begin:/registry/jobs/openstack/senlin-db-sync; range_end:; response_count:0; response_revision:18074; }","duration":"124.849029ms","start":"2026-10-17T11:30:40.797175Z","end":"2026-10-17T11:30:40.922024Z","steps":["trace[1932921129] 'agreement among raft nodes before linearized reading' (duration: 124.58083ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:33:01.585567Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:33:01.591858Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-17T11:33:01.591975Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-17T11:34:40.85439Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17410} {"level":"info","ts":"2026-10-17T11:34:40.951589Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17410,"took":"93.947951ms","hash":1202958796,"current-db-size-bytes":55681024,"current-db-size":"56 MB","current-db-size-in-use-bytes":34742272,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-17T11:34:40.951735Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1202958796,"revision":17410,"compact-revision":15178} {"level":"info","ts":"2026-10-17T11:36:06.476853Z","caller":"traceutil/trace.go:171","msg":"trace[1882450152] transaction","detail":"{read_only:false; response_revision:20712; number_of_response:1; }","duration":"112.569912ms","start":"2026-10-17T11:36:06.364239Z","end":"2026-10-17T11:36:06.476809Z","steps":["trace[1882450152] 'process raft request' (duration: 81.503027ms)","trace[1882450152] 'compare' (duration: 30.698224ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:37:39.074202Z","caller":"traceutil/trace.go:171","msg":"trace[2059601169] transaction","detail":"{read_only:false; response_revision:21303; number_of_response:1; }","duration":"404.740885ms","start":"2026-10-17T11:37:38.669435Z","end":"2026-10-17T11:37:39.074176Z","steps":["trace[2059601169] 'process raft request' (duration: 404.621981ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:37:39.074233Z","caller":"traceutil/trace.go:171","msg":"trace[748386657] linearizableReadLoop","detail":"{readStateIndex:22022; appliedIndex:22021; }","duration":"363.422413ms","start":"2026-10-17T11:37:38.71077Z","end":"2026-10-17T11:37:39.074193Z","steps":["trace[748386657] 'read index received' (duration: 363.365012ms)","trace[748386657] 'applied index is now lower than readState.Index' (duration: 53.731µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:37:39.074338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:38.669413Z","time spent":"404.859038ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:37:39.074564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.768184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-17T11:37:39.074602Z","caller":"traceutil/trace.go:171","msg":"trace[2075990724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21303; }","duration":"363.856507ms","start":"2026-10-17T11:37:38.710733Z","end":"2026-10-17T11:37:39.07459Z","steps":["trace[2075990724] 'agreement among raft nodes before linearized reading' (duration: 363.618419ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:39.074638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:38.710718Z","time spent":"363.911899ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-17T11:37:39.245269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.297551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:37:39.245358Z","caller":"traceutil/trace.go:171","msg":"trace[335653226] linearizableReadLoop","detail":"{readStateIndex:22023; appliedIndex:22022; }","duration":"171.027917ms","start":"2026-10-17T11:37:39.074316Z","end":"2026-10-17T11:37:39.245344Z","steps":["trace[335653226] 'read index received' (duration: 54.569561ms)","trace[335653226] 'applied index is now lower than readState.Index' (duration: 116.456876ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:37:39.245418Z","caller":"traceutil/trace.go:171","msg":"trace[1186683141] transaction","detail":"{read_only:false; response_revision:21304; number_of_response:1; }","duration":"437.360579ms","start":"2026-10-17T11:37:38.808048Z","end":"2026-10-17T11:37:39.245409Z","steps":["trace[1186683141] 'process raft request' (duration: 320.846652ms)","trace[1186683141] 'compare' (duration: 116.055243ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:37:39.245483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:38.80803Z","time spent":"437.426761ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:37:39.245672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.434337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-17T11:37:39.245691Z","caller":"traceutil/trace.go:171","msg":"trace[15744783] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21304; }","duration":"435.478399ms","start":"2026-10-17T11:37:38.810206Z","end":"2026-10-17T11:37:39.245684Z","steps":["trace[15744783] 'agreement among raft nodes before linearized reading' (duration: 435.367956ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:39.245711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:38.810192Z","time spent":"435.512481ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-17T11:37:39.245729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.677279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-10-17T11:37:39.245811Z","caller":"traceutil/trace.go:171","msg":"trace[529100300] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:21304; }","duration":"298.800012ms","start":"2026-10-17T11:37:38.946989Z","end":"2026-10-17T11:37:39.245789Z","steps":["trace[529100300] 'agreement among raft nodes before linearized reading' (duration: 298.572385ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:39.245864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.287214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-17T11:37:39.245893Z","caller":"traceutil/trace.go:171","msg":"trace[1975497414] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21304; }","duration":"475.354275ms","start":"2026-10-17T11:37:38.77053Z","end":"2026-10-17T11:37:39.245884Z","steps":["trace[1975497414] 'agreement among raft nodes before linearized reading' (duration: 475.281353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:39.245911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:38.77051Z","time spent":"475.396157ms","remote":"127.0.0.1:55922","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-10-17T11:37:39.246016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.190443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-17T11:37:39.246042Z","caller":"traceutil/trace.go:171","msg":"trace[531656618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21304; }","duration":"535.228025ms","start":"2026-10-17T11:37:38.710808Z","end":"2026-10-17T11:37:39.246036Z","steps":["trace[531656618] 'agreement among raft nodes before linearized reading' (duration: 535.164333ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:39.246057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:38.710802Z","time spent":"535.251056ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-17T11:37:39.246102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.169414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-17T11:37:39.246162Z","caller":"traceutil/trace.go:171","msg":"trace[1060124977] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21304; }","duration":"259.243047ms","start":"2026-10-17T11:37:38.986904Z","end":"2026-10-17T11:37:39.246147Z","steps":["trace[1060124977] 'agreement among raft nodes before linearized reading' (duration: 259.114733ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:39.246382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.414937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-10-17T11:37:39.24653Z","caller":"traceutil/trace.go:171","msg":"trace[875550758] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:21304; }","duration":"262.580432ms","start":"2026-10-17T11:37:38.983902Z","end":"2026-10-17T11:37:39.246482Z","steps":["trace[875550758] 'agreement among raft nodes before linearized reading' (duration: 262.222161ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:39.561026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.879542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:37:39.561184Z","caller":"traceutil/trace.go:171","msg":"trace[2078302624] linearizableReadLoop","detail":"{readStateIndex:22024; appliedIndex:22023; }","duration":"315.783342ms","start":"2026-10-17T11:37:39.245377Z","end":"2026-10-17T11:37:39.56116Z","steps":["trace[2078302624] 'read index received' (duration: 104.62206ms)","trace[2078302624] 'applied index is now lower than readState.Index' (duration: 211.159682ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:37:39.561511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.333884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:37:39.561512Z","caller":"traceutil/trace.go:171","msg":"trace[1443836484] transaction","detail":"{read_only:false; response_revision:21305; number_of_response:1; }","duration":"482.408148ms","start":"2026-10-17T11:37:39.079022Z","end":"2026-10-17T11:37:39.56143Z","steps":["trace[1443836484] 'process raft request' (duration: 271.02945ms)","trace[1443836484] 'compare' (duration: 210.755239ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:37:39.561617Z","caller":"traceutil/trace.go:171","msg":"trace[475476963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21305; }","duration":"463.486769ms","start":"2026-10-17T11:37:39.098105Z","end":"2026-10-17T11:37:39.561592Z","steps":["trace[475476963] 'agreement among raft nodes before linearized reading' (duration: 463.274703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:39.561673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:39.098085Z","time spent":"463.574921ms","remote":"127.0.0.1:55636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-17T11:37:39.561722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:39.078997Z","time spent":"482.622055ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:37:39.568361Z","caller":"traceutil/trace.go:171","msg":"trace[522463931] transaction","detail":"{read_only:false; response_revision:21306; number_of_response:1; }","duration":"318.166746ms","start":"2026-10-17T11:37:39.250173Z","end":"2026-10-17T11:37:39.56834Z","steps":["trace[522463931] 'process raft request' (duration: 317.927769ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:37:39.568423Z","caller":"traceutil/trace.go:171","msg":"trace[322787764] transaction","detail":"{read_only:false; response_revision:21307; number_of_response:1; }","duration":"316.422012ms","start":"2026-10-17T11:37:39.25196Z","end":"2026-10-17T11:37:39.568382Z","steps":["trace[322787764] 'process raft request' (duration: 316.276797ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:39.568489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:39.250155Z","time spent":"318.257019ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:37:39.568485Z","caller":"traceutil/trace.go:171","msg":"trace[869292652] transaction","detail":"{read_only:false; response_revision:21308; number_of_response:1; }","duration":"316.37631ms","start":"2026-10-17T11:37:39.252057Z","end":"2026-10-17T11:37:39.568433Z","steps":["trace[869292652] 'process raft request' (duration: 316.220755ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:39.568552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:39.25195Z","time spent":"316.559096ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:37:39.568587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:39.25205Z","time spent":"316.487243ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:37:39.568554Z","caller":"traceutil/trace.go:171","msg":"trace[1627163349] transaction","detail":"{read_only:false; response_revision:21309; number_of_response:1; }","duration":"316.442312ms","start":"2026-10-17T11:37:39.252091Z","end":"2026-10-17T11:37:39.568533Z","steps":["trace[1627163349] 'process raft request' (duration: 316.234456ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:37:39.568695Z","caller":"traceutil/trace.go:171","msg":"trace[1264827901] transaction","detail":"{read_only:false; response_revision:21310; number_of_response:1; }","duration":"316.355449ms","start":"2026-10-17T11:37:39.252328Z","end":"2026-10-17T11:37:39.568683Z","steps":["trace[1264827901] 'process raft request' (duration: 316.08036ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:39.568744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:39.252313Z","time spent":"316.402061ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:37:39.568759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.285752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-17T11:37:39.568778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:39.252064Z","time spent":"316.651689ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:37:39.568818Z","caller":"traceutil/trace.go:171","msg":"trace[1073114486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21310; }","duration":"175.401836ms","start":"2026-10-17T11:37:39.393398Z","end":"2026-10-17T11:37:39.5688Z","steps":["trace[1073114486] 'agreement among raft nodes before linearized reading' (duration: 175.250391ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:39.569059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.439377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-1cbb9a64-a3d8-43b8-9a3d-dfe7e2affaf7\" ","response":"range_response_count:1 size:1318"} {"level":"info","ts":"2026-10-17T11:37:39.569237Z","caller":"traceutil/trace.go:171","msg":"trace[343635347] range","detail":"{range_begin:/registry/persistentvolumes/pvc-1cbb9a64-a3d8-43b8-9a3d-dfe7e2affaf7; range_end:; response_count:1; response_revision:21310; }","duration":"319.655085ms","start":"2026-10-17T11:37:39.249561Z","end":"2026-10-17T11:37:39.569216Z","steps":["trace[343635347] 'agreement among raft nodes before linearized reading' (duration: 319.375105ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:39.569145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.108328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:30 size:261692"} {"level":"warn","ts":"2026-10-17T11:37:39.569304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:37:39.249541Z","time spent":"319.744898ms","remote":"127.0.0.1:55808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1341,"request content":"key:\"/registry/persistentvolumes/pvc-1cbb9a64-a3d8-43b8-9a3d-dfe7e2affaf7\" "} {"level":"info","ts":"2026-10-17T11:37:39.569339Z","caller":"traceutil/trace.go:171","msg":"trace[1581529992] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:30; response_revision:21310; }","duration":"118.437789ms","start":"2026-10-17T11:37:39.450891Z","end":"2026-10-17T11:37:39.569329Z","steps":["trace[1581529992] 'agreement among raft nodes before linearized reading' (duration: 118.005445ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:39.805751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.446363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:37:39.806088Z","caller":"traceutil/trace.go:171","msg":"trace[1444124869] transaction","detail":"{read_only:false; response_revision:21311; number_of_response:1; }","duration":"232.093376ms","start":"2026-10-17T11:37:39.57393Z","end":"2026-10-17T11:37:39.806024Z","steps":["trace[1444124869] 'process raft request' (duration: 99.267031ms)","trace[1444124869] 'compare' (duration: 132.270058ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:37:39.806148Z","caller":"traceutil/trace.go:171","msg":"trace[1507163211] transaction","detail":"{read_only:false; response_revision:21312; number_of_response:1; }","duration":"209.793258ms","start":"2026-10-17T11:37:39.596328Z","end":"2026-10-17T11:37:39.806121Z","steps":["trace[1507163211] 'process raft request' (duration: 209.53954ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:37:40.051494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.537242ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-17T11:39:40.861002Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20033} {"level":"info","ts":"2026-10-17T11:39:40.982365Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20033,"took":"117.161588ms","hash":672383293,"current-db-size-bytes":55681024,"current-db-size":"56 MB","current-db-size-in-use-bytes":31240192,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T11:39:40.982441Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":672383293,"revision":20033,"compact-revision":17410} {"level":"info","ts":"2026-10-17T11:44:33.190723Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006756.wal"} {"level":"info","ts":"2026-10-17T11:44:40.868432Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22294} {"level":"info","ts":"2026-10-17T11:44:41.002228Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22294,"took":"129.752918ms","hash":3101078760,"current-db-size-bytes":56524800,"current-db-size":"56 MB","current-db-size-in-use-bytes":45473792,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-10-17T11:44:41.002304Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3101078760,"revision":22294,"compact-revision":20033} {"level":"info","ts":"2026-10-17T11:44:42.056386Z","caller":"traceutil/trace.go:171","msg":"trace[1803947171] linearizableReadLoop","detail":"{readStateIndex:26525; appliedIndex:26524; }","duration":"155.828455ms","start":"2026-10-17T11:44:41.900521Z","end":"2026-10-17T11:44:42.056349Z","steps":["trace[1803947171] 'read index received' (duration: 154.482283ms)","trace[1803947171] 'applied index is now lower than readState.Index' (duration: 1.344892ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:44:42.056482Z","caller":"traceutil/trace.go:171","msg":"trace[1535289877] transaction","detail":"{read_only:false; response_revision:25683; number_of_response:1; }","duration":"156.169897ms","start":"2026-10-17T11:44:41.900277Z","end":"2026-10-17T11:44:42.056447Z","steps":["trace[1535289877] 'process raft request' (duration: 154.792413ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:44:42.056728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.552217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-17T11:44:42.056727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.186428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-17T11:44:42.056785Z","caller":"traceutil/trace.go:171","msg":"trace[972813627] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25683; }","duration":"109.664732ms","start":"2026-10-17T11:44:41.947099Z","end":"2026-10-17T11:44:42.056764Z","steps":["trace[972813627] 'agreement among raft nodes before linearized reading' (duration: 109.443535ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:44:42.056829Z","caller":"traceutil/trace.go:171","msg":"trace[125903439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25683; }","duration":"156.335352ms","start":"2026-10-17T11:44:41.900468Z","end":"2026-10-17T11:44:42.056804Z","steps":["trace[125903439] 'agreement among raft nodes before linearized reading' (duration: 156.039953ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:44:42.404873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.244566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:44:42.405088Z","caller":"traceutil/trace.go:171","msg":"trace[788265074] transaction","detail":"{read_only:false; response_revision:25685; number_of_response:1; }","duration":"341.85948ms","start":"2026-10-17T11:44:42.063188Z","end":"2026-10-17T11:44:42.405047Z","steps":["trace[788265074] 'process raft request' (duration: 167.435034ms)","trace[788265074] 'compare' (duration: 165.660118ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:44:42.405233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:44:42.063175Z","time spent":"341.993985ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:44:42.405803Z","caller":"traceutil/trace.go:171","msg":"trace[136154233] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"192.163278ms","start":"2026-10-17T11:44:42.21363Z","end":"2026-10-17T11:44:42.405793Z","steps":["trace[136154233] 'process raft request' (duration: 192.128387ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:44:42.405997Z","caller":"traceutil/trace.go:171","msg":"trace[133081713] linearizableReadLoop","detail":"{readStateIndex:26528; appliedIndex:26526; }","duration":"308.336016ms","start":"2026-10-17T11:44:42.09764Z","end":"2026-10-17T11:44:42.405976Z","steps":["trace[133081713] 'read index received' (duration: 133.000252ms)","trace[133081713] 'applied index is now lower than readState.Index' (duration: 175.295553ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:44:42.406008Z","caller":"traceutil/trace.go:171","msg":"trace[267764846] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"342.558692ms","start":"2026-10-17T11:44:42.063363Z","end":"2026-10-17T11:44:42.405922Z","steps":["trace[267764846] 'process raft request' (duration: 341.634493ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:44:42.406124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.488531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:44:42.406212Z","caller":"traceutil/trace.go:171","msg":"trace[1336413206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25687; }","duration":"308.656866ms","start":"2026-10-17T11:44:42.097545Z","end":"2026-10-17T11:44:42.406202Z","steps":["trace[1336413206] 'agreement among raft nodes before linearized reading' (duration: 308.541682ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:44:42.406242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:44:42.097523Z","time spent":"308.713318ms","remote":"127.0.0.1:55636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-17T11:44:42.406369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:44:42.063329Z","time spent":"342.79064ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:44:42.406499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.775649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-17T11:44:42.406536Z","caller":"traceutil/trace.go:171","msg":"trace[298281367] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25687; }","duration":"204.852111ms","start":"2026-10-17T11:44:42.201673Z","end":"2026-10-17T11:44:42.406525Z","steps":["trace[298281367] 'agreement among raft nodes before linearized reading' (duration: 204.794709ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:44:42.406504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.786216ms","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-10-17T11:44:42.406696Z","caller":"traceutil/trace.go:171","msg":"trace[1744251285] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25687; }","duration":"277.976753ms","start":"2026-10-17T11:44:42.128706Z","end":"2026-10-17T11:44:42.406683Z","steps":["trace[1744251285] 'agreement among raft nodes before linearized reading' (duration: 277.483257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:44:42.406734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.414176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-17T11:44:42.406794Z","caller":"traceutil/trace.go:171","msg":"trace[722314645] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25687; }","duration":"226.529409ms","start":"2026-10-17T11:44:42.180254Z","end":"2026-10-17T11:44:42.406784Z","steps":["trace[722314645] 'agreement among raft nodes before linearized reading' (duration: 226.315252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:44:42.406745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.77678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-10-17T11:44:42.406915Z","caller":"traceutil/trace.go:171","msg":"trace[510984182] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25687; }","duration":"181.984386ms","start":"2026-10-17T11:44:42.224921Z","end":"2026-10-17T11:44:42.406906Z","steps":["trace[510984182] 'agreement among raft nodes before linearized reading' (duration: 181.724878ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:44:42.406927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.255918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-17T11:44:42.406958Z","caller":"traceutil/trace.go:171","msg":"trace[1876218022] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:25687; }","duration":"258.3289ms","start":"2026-10-17T11:44:42.14862Z","end":"2026-10-17T11:44:42.406949Z","steps":["trace[1876218022] 'agreement among raft nodes before linearized reading' (duration: 258.266258ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:44:42.80374Z","caller":"traceutil/trace.go:171","msg":"trace[1331958682] linearizableReadLoop","detail":"{readStateIndex:26533; appliedIndex:26532; }","duration":"279.505012ms","start":"2026-10-17T11:44:42.524198Z","end":"2026-10-17T11:44:42.803703Z","steps":["trace[1331958682] 'read index received' (duration: 279.155131ms)","trace[1331958682] 'applied index is now lower than readState.Index' (duration: 348.671µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:44:42.804062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.822571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-17T11:44:42.804129Z","caller":"traceutil/trace.go:171","msg":"trace[1574278344] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25691; }","duration":"279.946035ms","start":"2026-10-17T11:44:42.524149Z","end":"2026-10-17T11:44:42.804095Z","steps":["trace[1574278344] 'agreement among raft nodes before linearized reading' (duration: 279.712038ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:44:42.80463Z","caller":"traceutil/trace.go:171","msg":"trace[1596491961] transaction","detail":"{read_only:false; response_revision:25691; number_of_response:1; }","duration":"316.536667ms","start":"2026-10-17T11:44:42.488081Z","end":"2026-10-17T11:44:42.804618Z","steps":["trace[1596491961] 'process raft request' (duration: 315.38808ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:44:42.804749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:44:42.488064Z","time spent":"316.604209ms","remote":"127.0.0.1:55726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:45:03.780152Z","caller":"traceutil/trace.go:171","msg":"trace[2138354601] linearizableReadLoop","detail":"{readStateIndex:26810; appliedIndex:26808; }","duration":"105.419316ms","start":"2026-10-17T11:45:03.674706Z","end":"2026-10-17T11:45:03.780125Z","steps":["trace[2138354601] 'read index received' (duration: 54.622404ms)","trace[2138354601] 'applied index is now lower than readState.Index' (duration: 50.795832ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:45:03.780277Z","caller":"traceutil/trace.go:171","msg":"trace[387391383] transaction","detail":"{read_only:false; response_revision:25964; number_of_response:1; }","duration":"108.928738ms","start":"2026-10-17T11:45:03.671312Z","end":"2026-10-17T11:45:03.780241Z","steps":["trace[387391383] 'process raft request' (duration: 96.624337ms)","trace[387391383] 'compare' (duration: 12.077773ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:45:03.780465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.612013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-17T11:45:03.780532Z","caller":"traceutil/trace.go:171","msg":"trace[1134326126] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:25964; }","duration":"105.85653ms","start":"2026-10-17T11:45:03.674664Z","end":"2026-10-17T11:45:03.78052Z","steps":["trace[1134326126] 'agreement among raft nodes before linearized reading' (duration: 105.595432ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:45:04.14569Z","caller":"traceutil/trace.go:171","msg":"trace[1875626207] transaction","detail":"{read_only:false; response_revision:25968; number_of_response:1; }","duration":"102.540975ms","start":"2026-10-17T11:45:04.043126Z","end":"2026-10-17T11:45:04.145667Z","steps":["trace[1875626207] 'process raft request' (duration: 65.743016ms)","trace[1875626207] 'compare' (duration: 36.487848ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:45:45.603083Z","caller":"traceutil/trace.go:171","msg":"trace[1896870449] linearizableReadLoop","detail":"{readStateIndex:27171; appliedIndex:27170; }","duration":"109.417443ms","start":"2026-10-17T11:45:45.493641Z","end":"2026-10-17T11:45:45.603059Z","steps":["trace[1896870449] 'read index received' (duration: 109.390442ms)","trace[1896870449] 'applied index is now lower than readState.Index' (duration: 25.981µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:45:45.603222Z","caller":"traceutil/trace.go:171","msg":"trace[1793208842] transaction","detail":"{read_only:false; response_revision:26316; number_of_response:1; }","duration":"110.959711ms","start":"2026-10-17T11:45:45.492247Z","end":"2026-10-17T11:45:45.603207Z","steps":["trace[1793208842] 'process raft request' (duration: 110.650941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:45.60333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.669111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:45:45.60339Z","caller":"traceutil/trace.go:171","msg":"trace[1569736324] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:26316; }","duration":"109.801656ms","start":"2026-10-17T11:45:45.493572Z","end":"2026-10-17T11:45:45.603374Z","steps":["trace[1569736324] 'agreement among raft nodes before linearized reading' (duration: 109.707492ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:45.624921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.073592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:45:45.625022Z","caller":"traceutil/trace.go:171","msg":"trace[1533917655] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:26318; }","duration":"115.214657ms","start":"2026-10-17T11:45:45.509785Z","end":"2026-10-17T11:45:45.625Z","steps":["trace[1533917655] 'agreement among raft nodes before linearized reading' (duration: 115.044092ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:45:45.838166Z","caller":"traceutil/trace.go:171","msg":"trace[1685716454] linearizableReadLoop","detail":"{readStateIndex:27174; appliedIndex:27173; }","duration":"104.117695ms","start":"2026-10-17T11:45:45.734026Z","end":"2026-10-17T11:45:45.838144Z","steps":["trace[1685716454] 'read index received' (duration: 79.558996ms)","trace[1685716454] 'applied index is now lower than readState.Index' (duration: 24.557859ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:45:45.838377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.328721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-17T11:45:45.838415Z","caller":"traceutil/trace.go:171","msg":"trace[478270631] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:26319; }","duration":"104.409004ms","start":"2026-10-17T11:45:45.733995Z","end":"2026-10-17T11:45:45.838404Z","steps":["trace[478270631] 'agreement among raft nodes before linearized reading' (duration: 104.234769ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:45:45.838584Z","caller":"traceutil/trace.go:171","msg":"trace[143096646] transaction","detail":"{read_only:false; response_revision:26319; number_of_response:1; }","duration":"207.270729ms","start":"2026-10-17T11:45:45.631301Z","end":"2026-10-17T11:45:45.838572Z","steps":["trace[143096646] 'process raft request' (duration: 182.38125ms)","trace[143096646] 'compare' (duration: 24.368983ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:45:46.201454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.795143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:45:46.201554Z","caller":"traceutil/trace.go:171","msg":"trace[1278237344] transaction","detail":"{read_only:false; response_revision:26320; number_of_response:1; }","duration":"264.520565ms","start":"2026-10-17T11:45:45.937011Z","end":"2026-10-17T11:45:46.201532Z","steps":["trace[1278237344] 'process raft request' (duration: 86.571867ms)","trace[1278237344] 'compare' (duration: 177.69556ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:45:46.577497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.805511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:45:46.577617Z","caller":"traceutil/trace.go:171","msg":"trace[198077862] linearizableReadLoop","detail":"{readStateIndex:27176; appliedIndex:27175; }","duration":"225.577849ms","start":"2026-10-17T11:45:46.352004Z","end":"2026-10-17T11:45:46.577582Z","steps":["trace[198077862] 'read index received' (duration: 43.616364ms)","trace[198077862] 'applied index is now lower than readState.Index' (duration: 181.960035ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:45:46.577697Z","caller":"traceutil/trace.go:171","msg":"trace[64248543] transaction","detail":"{read_only:false; response_revision:26321; number_of_response:1; }","duration":"315.71756ms","start":"2026-10-17T11:45:46.261945Z","end":"2026-10-17T11:45:46.577663Z","steps":["trace[64248543] 'process raft request' (duration: 133.666012ms)","trace[64248543] 'compare' (duration: 181.540342ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:45:46.577739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.743834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:45:46.57777Z","caller":"traceutil/trace.go:171","msg":"trace[830197687] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:26321; }","duration":"225.811427ms","start":"2026-10-17T11:45:46.351947Z","end":"2026-10-17T11:45:46.577758Z","steps":["trace[830197687] 'agreement among raft nodes before linearized reading' (duration: 225.750165ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:46.577851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:45:46.261927Z","time spent":"315.833234ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:45:46.578062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.586349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-17T11:45:46.578133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.136792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-17T11:45:46.578133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.143626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-17T11:45:46.578125Z","caller":"traceutil/trace.go:171","msg":"trace[1231739587] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26321; }","duration":"156.713924ms","start":"2026-10-17T11:45:46.421392Z","end":"2026-10-17T11:45:46.578106Z","steps":["trace[1231739587] 'agreement among raft nodes before linearized reading' (duration: 156.554619ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:45:46.578188Z","caller":"traceutil/trace.go:171","msg":"trace[1847336836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26321; }","duration":"145.225519ms","start":"2026-10-17T11:45:46.432948Z","end":"2026-10-17T11:45:46.578174Z","steps":["trace[1847336836] 'agreement among raft nodes before linearized reading' (duration: 145.028223ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:45:46.578174Z","caller":"traceutil/trace.go:171","msg":"trace[195451595] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:26321; }","duration":"154.196485ms","start":"2026-10-17T11:45:46.423964Z","end":"2026-10-17T11:45:46.578161Z","steps":["trace[195451595] 'agreement among raft nodes before linearized reading' (duration: 154.03931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:46.952759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.379144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T11:45:46.952879Z","caller":"traceutil/trace.go:171","msg":"trace[1239170071] transaction","detail":"{read_only:false; response_revision:26322; number_of_response:1; }","duration":"370.381806ms","start":"2026-10-17T11:45:46.582477Z","end":"2026-10-17T11:45:46.952859Z","steps":["trace[1239170071] 'process raft request' (duration: 155.791865ms)","trace[1239170071] 'compare' (duration: 213.664492ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T11:45:46.952949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:45:46.582457Z","time spent":"370.456698ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T11:45:47.020915Z","caller":"traceutil/trace.go:171","msg":"trace[21311572] linearizableReadLoop","detail":"{readStateIndex:27179; appliedIndex:27177; }","duration":"239.357327ms","start":"2026-10-17T11:45:46.781535Z","end":"2026-10-17T11:45:47.020892Z","steps":["trace[21311572] 'read index received' (duration: 239.16588ms)","trace[21311572] 'applied index is now lower than readState.Index' (duration: 190.357µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T11:45:47.020975Z","caller":"traceutil/trace.go:171","msg":"trace[1908181246] transaction","detail":"{read_only:false; response_revision:26323; number_of_response:1; }","duration":"437.094043ms","start":"2026-10-17T11:45:46.583851Z","end":"2026-10-17T11:45:47.020945Z","steps":["trace[1908181246] 'process raft request' (duration: 436.870695ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:45:47.021042Z","caller":"traceutil/trace.go:171","msg":"trace[1945251671] transaction","detail":"{read_only:false; response_revision:26324; number_of_response:1; }","duration":"347.563162ms","start":"2026-10-17T11:45:46.673467Z","end":"2026-10-17T11:45:47.02103Z","steps":["trace[1945251671] 'process raft request' (duration: 347.385396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:47.021136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:45:46.583829Z","time spent":"437.207517ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:45:47.021381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.129067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-17T11:45:47.021427Z","caller":"traceutil/trace.go:171","msg":"trace[1909554843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26324; }","duration":"208.22985ms","start":"2026-10-17T11:45:46.813185Z","end":"2026-10-17T11:45:47.021415Z","steps":["trace[1909554843] 'agreement among raft nodes before linearized reading' (duration: 208.091566ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:47.021537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.998657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:45:47.021564Z","caller":"traceutil/trace.go:171","msg":"trace[1477571605] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:26324; }","duration":"240.06613ms","start":"2026-10-17T11:45:46.781491Z","end":"2026-10-17T11:45:47.021557Z","steps":["trace[1477571605] 'agreement among raft nodes before linearized reading' (duration: 240.020619ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:47.021765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.298889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T11:45:47.0218Z","caller":"traceutil/trace.go:171","msg":"trace[20177382] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:26324; }","duration":"109.367962ms","start":"2026-10-17T11:45:46.912421Z","end":"2026-10-17T11:45:47.021789Z","steps":["trace[20177382] 'agreement among raft nodes before linearized reading' (duration: 109.31337ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:47.02197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.329522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-17T11:45:47.022Z","caller":"traceutil/trace.go:171","msg":"trace[368198874] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26324; }","duration":"162.432805ms","start":"2026-10-17T11:45:46.85956Z","end":"2026-10-17T11:45:47.021993Z","steps":["trace[368198874] 'agreement among raft nodes before linearized reading' (duration: 162.352662ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:47.022106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.927948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-17T11:45:47.022132Z","caller":"traceutil/trace.go:171","msg":"trace[1550450338] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26324; }","duration":"201.998701ms","start":"2026-10-17T11:45:46.820126Z","end":"2026-10-17T11:45:47.022125Z","steps":["trace[1550450338] 'agreement among raft nodes before linearized reading' (duration: 201.931449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T11:45:47.022278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T11:45:46.673441Z","time spent":"347.626924ms","remote":"127.0.0.1:55922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T11:45:47.022382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.991485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-10-17T11:45:47.02244Z","caller":"traceutil/trace.go:171","msg":"trace[1376190869] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:26324; }","duration":"204.072007ms","start":"2026-10-17T11:45:46.818351Z","end":"2026-10-17T11:45:47.022423Z","steps":["trace[1376190869] 'agreement among raft nodes before linearized reading' (duration: 203.898812ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T11:49:40.875581Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25677} {"level":"info","ts":"2026-10-17T11:49:41.04173Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25677,"took":"161.296742ms","hash":1725163893,"current-db-size-bytes":58634240,"current-db-size":"59 MB","current-db-size-in-use-bytes":36663296,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-17T11:49:41.0418Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1725163893,"revision":25677,"compact-revision":22294} {"level":"info","ts":"2026-10-17T11:50:26.622675Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T11:50:26.629451Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-17T11:50:26.629709Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-17T11:54:40.882562Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28652} {"level":"info","ts":"2026-10-17T11:54:41.034824Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28652,"took":"147.930169ms","hash":2159148946,"current-db-size-bytes":58634240,"current-db-size":"59 MB","current-db-size-in-use-bytes":32591872,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-17T11:54:41.034891Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2159148946,"revision":28652,"compact-revision":25677} {"level":"info","ts":"2026-10-17T11:59:40.888897Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30933} {"level":"info","ts":"2026-10-17T11:59:41.042729Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30933,"took":"150.976954ms","hash":2515654371,"current-db-size-bytes":58634240,"current-db-size":"59 MB","current-db-size-in-use-bytes":31846400,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T11:59:41.042859Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2515654371,"revision":30933,"compact-revision":28652} {"level":"info","ts":"2026-10-17T12:04:40.89649Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33233} {"level":"info","ts":"2026-10-17T12:04:41.071641Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33233,"took":"170.385551ms","hash":4017106899,"current-db-size-bytes":59678720,"current-db-size":"60 MB","current-db-size-in-use-bytes":32419840,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T12:04:41.071715Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4017106899,"revision":33233,"compact-revision":30933} {"level":"info","ts":"2026-10-17T12:09:40.904222Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35636} {"level":"info","ts":"2026-10-17T12:09:41.055937Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35636,"took":"147.096671ms","hash":2062653495,"current-db-size-bytes":59678720,"current-db-size":"60 MB","current-db-size-in-use-bytes":33247232,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-17T12:09:41.056141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2062653495,"revision":35636,"compact-revision":33233} {"level":"info","ts":"2026-10-17T12:11:17.21203Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T12:11:17.218141Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-17T12:11:17.218539Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-17T12:14:40.911984Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37973} {"level":"info","ts":"2026-10-17T12:14:41.067852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37973,"took":"152.394437ms","hash":4173106592,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":31981568,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T12:14:41.06795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4173106592,"revision":37973,"compact-revision":35636}