{"level":"warn","ts":"2026-03-19T17:52:06.54174Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T17:52:06.541848Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.121:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.121:2380","--initial-cluster=instance=https://199.19.213.121:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.121:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.121:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-19T17:52:06.541926Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T17:52:06.54194Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.121:2380"]} {"level":"info","ts":"2026-03-19T17:52:06.541962Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-19T17:52:06.542811Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"]} {"level":"info","ts":"2026-03-19T17:52:06.542902Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.121:2380"],"listen-peer-urls":["https://199.19.213.121:2380"],"advertise-client-urls":["https://199.19.213.121:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.121:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-19T17:52:06.547331Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.19568ms"} {"level":"info","ts":"2026-03-19T17:52:06.566128Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"31541ae44470b70","cluster-id":"8ae2d89c48d1447d"} {"level":"info","ts":"2026-03-19T17:52:06.566297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T17:52:06.566326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became follower at term 0"} {"level":"info","ts":"2026-03-19T17:52:06.566346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 31541ae44470b70 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T17:52:06.566358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became follower at term 1"} {"level":"info","ts":"2026-03-19T17:52:06.566426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=(222155973350329200)"} {"level":"warn","ts":"2026-03-19T17:52:06.577061Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T17:52:06.578965Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T17:52:06.580282Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-19T17:52:06.58323Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"31541ae44470b70","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T17:52:06.583516Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"31541ae44470b70","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T17:52:06.583609Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-19T17:52:06.583758Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-19T17:52:06.583781Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-19T17:52:06.585062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=(222155973350329200)"} {"level":"info","ts":"2026-03-19T17:52:06.585567Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ae2d89c48d1447d","local-member-id":"31541ae44470b70","added-peer-id":"31541ae44470b70","added-peer-peer-urls":["https://199.19.213.121:2380"]} {"level":"info","ts":"2026-03-19T17:52:06.587473Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-19T17:52:06.587558Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.121:2380"} {"level":"info","ts":"2026-03-19T17:52:06.587623Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.121:2380"} {"level":"info","ts":"2026-03-19T17:52:06.587784Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"31541ae44470b70","initial-advertise-peer-urls":["https://199.19.213.121:2380"],"listen-peer-urls":["https://199.19.213.121:2380"],"advertise-client-urls":["https://199.19.213.121:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T17:52:06.587854Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T17:52:06.666982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T17:52:06.667122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T17:52:06.667148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 received MsgPreVoteResp from 31541ae44470b70 at term 1"} {"level":"info","ts":"2026-03-19T17:52:06.66717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became candidate at term 2"} {"level":"info","ts":"2026-03-19T17:52:06.667182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 received MsgVoteResp from 31541ae44470b70 at term 2"} {"level":"info","ts":"2026-03-19T17:52:06.667198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became leader at term 2"} {"level":"info","ts":"2026-03-19T17:52:06.667212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 31541ae44470b70 elected leader 31541ae44470b70 at term 2"} {"level":"info","ts":"2026-03-19T17:52:06.668248Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"31541ae44470b70","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.121:2379]}","request-path":"/0/members/31541ae44470b70/attributes","cluster-id":"8ae2d89c48d1447d","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T17:52:06.668331Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T17:52:06.668477Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:52:06.668575Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T17:52:06.668636Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T17:52:06.668454Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T17:52:06.669356Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ae2d89c48d1447d","local-member-id":"31541ae44470b70","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:52:06.669474Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:52:06.669501Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:52:06.673701Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-19T17:52:06.674483Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.121:2379"} {"level":"info","ts":"2026-03-19T17:52:26.505677Z","caller":"traceutil/trace.go:171","msg":"trace[508365445] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"108.615114ms","start":"2026-03-19T17:52:26.397029Z","end":"2026-03-19T17:52:26.505644Z","steps":["trace[508365445] 'process raft request' (duration: 108.37703ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:30.627226Z","caller":"traceutil/trace.go:171","msg":"trace[340004138] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"101.150164ms","start":"2026-03-19T17:52:30.526044Z","end":"2026-03-19T17:52:30.627194Z","steps":["trace[340004138] 'process raft request' (duration: 100.94568ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:35.986153Z","caller":"traceutil/trace.go:171","msg":"trace[921474611] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"163.082583ms","start":"2026-03-19T17:52:35.823054Z","end":"2026-03-19T17:52:35.986136Z","steps":["trace[921474611] 'process raft request' (duration: 162.833749ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:35.986661Z","caller":"traceutil/trace.go:171","msg":"trace[584599047] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:511; }","duration":"132.740816ms","start":"2026-03-19T17:52:35.853905Z","end":"2026-03-19T17:52:35.986646Z","steps":["trace[584599047] 'read index received' (duration: 131.95632ms)","trace[584599047] 'applied index is now lower than readState.Index' (duration: 783.836µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:52:35.986771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.847288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-xfwqm\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-03-19T17:52:35.986823Z","caller":"traceutil/trace.go:171","msg":"trace[2141693969] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-xfwqm; range_end:; response_count:1; response_revision:499; }","duration":"132.95384ms","start":"2026-03-19T17:52:35.853862Z","end":"2026-03-19T17:52:35.986816Z","steps":["trace[2141693969] 'agreement among raft nodes before linearized reading' (duration: 132.842248ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:35.986999Z","caller":"traceutil/trace.go:171","msg":"trace[535720049] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"152.870753ms","start":"2026-03-19T17:52:35.834118Z","end":"2026-03-19T17:52:35.986989Z","steps":["trace[535720049] 'process raft request' (duration: 152.137719ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:59.878227Z","caller":"traceutil/trace.go:171","msg":"trace[1546854449] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"115.417091ms","start":"2026-03-19T17:52:59.762784Z","end":"2026-03-19T17:52:59.878201Z","steps":["trace[1546854449] 'process raft request' (duration: 113.447473ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:29.799294Z","caller":"traceutil/trace.go:171","msg":"trace[1693247526] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"132.055539ms","start":"2026-03-19T17:53:29.667213Z","end":"2026-03-19T17:53:29.799269Z","steps":["trace[1693247526] 'process raft request' (duration: 131.909797ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:58.848244Z","caller":"traceutil/trace.go:171","msg":"trace[1945648280] linearizableReadLoop","detail":"{readStateIndex:1656; appliedIndex:1655; }","duration":"221.89029ms","start":"2026-03-19T17:53:58.626332Z","end":"2026-03-19T17:53:58.848222Z","steps":["trace[1945648280] 'read index received' (duration: 221.757317ms)","trace[1945648280] 'applied index is now lower than readState.Index' (duration: 129.853µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:53:58.848427Z","caller":"traceutil/trace.go:171","msg":"trace[1930384822] transaction","detail":"{read_only:false; response_revision:1599; number_of_response:1; }","duration":"315.488327ms","start":"2026-03-19T17:53:58.532871Z","end":"2026-03-19T17:53:58.848359Z","steps":["trace[1930384822] 'process raft request' (duration: 315.190522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:58.848626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.244097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ac8ee033-c7d7-4490-a2d3-66b926b6df47\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-03-19T17:53:58.848797Z","caller":"traceutil/trace.go:171","msg":"trace[1194363184] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ac8ee033-c7d7-4490-a2d3-66b926b6df47; range_end:; response_count:1; response_revision:1599; }","duration":"222.48727ms","start":"2026-03-19T17:53:58.626267Z","end":"2026-03-19T17:53:58.848754Z","steps":["trace[1194363184] 'agreement among raft nodes before linearized reading' (duration: 222.158854ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:58.849254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:58.532843Z","time spent":"315.686131ms","remote":"127.0.0.1:33830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:53:59.33958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.385329ms","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-03-19T17:53:59.339799Z","caller":"traceutil/trace.go:171","msg":"trace[1675526062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1601; }","duration":"108.661744ms","start":"2026-03-19T17:53:59.231103Z","end":"2026-03-19T17:53:59.339765Z","steps":["trace[1675526062] 'range keys from in-memory index tree' (duration: 108.162655ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:48.817164Z","caller":"traceutil/trace.go:171","msg":"trace[1697022] linearizableReadLoop","detail":"{readStateIndex:1971; appliedIndex:1970; }","duration":"154.33191ms","start":"2026-03-19T17:54:48.662793Z","end":"2026-03-19T17:54:48.817124Z","steps":["trace[1697022] 'read index received' (duration: 154.073676ms)","trace[1697022] 'applied index is now lower than readState.Index' (duration: 256.404µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:48.817221Z","caller":"traceutil/trace.go:171","msg":"trace[571479043] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"177.14669ms","start":"2026-03-19T17:54:48.640028Z","end":"2026-03-19T17:54:48.817175Z","steps":["trace[571479043] 'process raft request' (duration: 176.849615ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:48.81753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.689636ms","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-03-19T17:54:48.817672Z","caller":"traceutil/trace.go:171","msg":"trace[1473140135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1902; }","duration":"154.883999ms","start":"2026-03-19T17:54:48.662751Z","end":"2026-03-19T17:54:48.817635Z","steps":["trace[1473140135] 'agreement among raft nodes before linearized reading' (duration: 154.515843ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:48.817686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.888241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-19T17:54:48.817749Z","caller":"traceutil/trace.go:171","msg":"trace[1615118519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1902; }","duration":"112.996482ms","start":"2026-03-19T17:54:48.704733Z","end":"2026-03-19T17:54:48.81773Z","steps":["trace[1615118519] 'agreement among raft nodes before linearized reading' (duration: 112.82589ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:48.818364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.384933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-03-19T17:54:48.818433Z","caller":"traceutil/trace.go:171","msg":"trace[1746228453] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1902; }","duration":"129.506315ms","start":"2026-03-19T17:54:48.688907Z","end":"2026-03-19T17:54:48.818413Z","steps":["trace[1746228453] 'agreement among raft nodes before linearized reading' (duration: 128.59793ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:48.95209Z","caller":"traceutil/trace.go:171","msg":"trace[2085732765] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"129.331883ms","start":"2026-03-19T17:54:48.822731Z","end":"2026-03-19T17:54:48.952062Z","steps":["trace[2085732765] 'process raft request' (duration: 92.712896ms)","trace[2085732765] 'compare' (duration: 36.438624ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:48.952523Z","caller":"traceutil/trace.go:171","msg":"trace[1467254819] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"128.648241ms","start":"2026-03-19T17:54:48.823851Z","end":"2026-03-19T17:54:48.952499Z","steps":["trace[1467254819] 'process raft request' (duration: 128.497979ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:48.952562Z","caller":"traceutil/trace.go:171","msg":"trace[1455465971] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"129.17304ms","start":"2026-03-19T17:54:48.823338Z","end":"2026-03-19T17:54:48.952511Z","steps":["trace[1455465971] 'process raft request' (duration: 128.683082ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:56:11.676859Z","caller":"traceutil/trace.go:171","msg":"trace[857085321] transaction","detail":"{read_only:false; response_revision:2503; number_of_response:1; }","duration":"193.767408ms","start":"2026-03-19T17:56:11.483061Z","end":"2026-03-19T17:56:11.676828Z","steps":["trace[857085321] 'process raft request' (duration: 151.888636ms)","trace[857085321] 'compare' (duration: 41.675118ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:56:11.963948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.631393ms","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-03-19T17:56:11.964031Z","caller":"traceutil/trace.go:171","msg":"trace[244569104] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2504; }","duration":"179.777466ms","start":"2026-03-19T17:56:11.784236Z","end":"2026-03-19T17:56:11.964014Z","steps":["trace[244569104] 'range keys from in-memory index tree' (duration: 179.251497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:56:11.964111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.496936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-03-19T17:56:11.964225Z","caller":"traceutil/trace.go:171","msg":"trace[1503567867] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2504; }","duration":"115.665579ms","start":"2026-03-19T17:56:11.848534Z","end":"2026-03-19T17:56:11.9642Z","steps":["trace[1503567867] 'range keys from in-memory index tree' (duration: 115.289642ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:56:12.133356Z","caller":"traceutil/trace.go:171","msg":"trace[592617140] linearizableReadLoop","detail":"{readStateIndex:2598; appliedIndex:2597; }","duration":"162.745749ms","start":"2026-03-19T17:56:11.970584Z","end":"2026-03-19T17:56:12.13333Z","steps":["trace[592617140] 'read index received' (duration: 162.648397ms)","trace[592617140] 'applied index is now lower than readState.Index' (duration: 96.002µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:56:12.133691Z","caller":"traceutil/trace.go:171","msg":"trace[1602679442] transaction","detail":"{read_only:false; response_revision:2505; number_of_response:1; }","duration":"164.924278ms","start":"2026-03-19T17:56:11.968739Z","end":"2026-03-19T17:56:12.133663Z","steps":["trace[1602679442] 'process raft request' (duration: 164.413549ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:56:12.133924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.32415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1315"} {"level":"info","ts":"2026-03-19T17:56:12.134021Z","caller":"traceutil/trace.go:171","msg":"trace[388371711] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2505; }","duration":"163.446562ms","start":"2026-03-19T17:56:11.970544Z","end":"2026-03-19T17:56:12.133991Z","steps":["trace[388371711] 'agreement among raft nodes before linearized reading' (duration: 163.168346ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:00:46.590345Z","caller":"traceutil/trace.go:171","msg":"trace[117544026] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"174.57516ms","start":"2026-03-19T18:00:46.415741Z","end":"2026-03-19T18:00:46.590317Z","steps":["trace[117544026] 'process raft request' (duration: 174.390097ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:40.158853Z","caller":"traceutil/trace.go:171","msg":"trace[1716267278] linearizableReadLoop","detail":"{readStateIndex:5498; appliedIndex:5497; }","duration":"144.471863ms","start":"2026-03-19T18:01:40.01436Z","end":"2026-03-19T18:01:40.158832Z","steps":["trace[1716267278] 'read index received' (duration: 144.331661ms)","trace[1716267278] 'applied index is now lower than readState.Index' (duration: 139.312µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:01:40.158919Z","caller":"traceutil/trace.go:171","msg":"trace[956064879] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"177.044477ms","start":"2026-03-19T18:01:39.981842Z","end":"2026-03-19T18:01:40.158886Z","steps":["trace[956064879] 'process raft request' (duration: 176.865444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:40.159079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.110436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.121\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-19T18:01:40.159115Z","caller":"traceutil/trace.go:171","msg":"trace[1963775744] range","detail":"{range_begin:/registry/masterleases/199.19.213.121; range_end:; response_count:1; response_revision:5296; }","duration":"135.176068ms","start":"2026-03-19T18:01:40.023928Z","end":"2026-03-19T18:01:40.159105Z","steps":["trace[1963775744] 'agreement among raft nodes before linearized reading' (duration: 135.045406ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:40.159122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.737578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-03-19T18:01:40.15918Z","caller":"traceutil/trace.go:171","msg":"trace[578244351] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5296; }","duration":"144.842419ms","start":"2026-03-19T18:01:40.014323Z","end":"2026-03-19T18:01:40.159165Z","steps":["trace[578244351] 'agreement among raft nodes before linearized reading' (duration: 144.622295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:40.403459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.757927ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-03-19T18:01:40.403689Z","caller":"traceutil/trace.go:171","msg":"trace[958776326] linearizableReadLoop","detail":"{readStateIndex:5499; appliedIndex:5498; }","duration":"241.517103ms","start":"2026-03-19T18:01:40.162151Z","end":"2026-03-19T18:01:40.403668Z","steps":["trace[958776326] 'read index received' (duration: 129.45111ms)","trace[958776326] 'applied index is now lower than readState.Index' (duration: 112.064153ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T18:01:40.404141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.968352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3213590f-2de7-43ac-97de-3c452f98a3c4\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-03-19T18:01:40.404241Z","caller":"traceutil/trace.go:171","msg":"trace[2134048281] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3213590f-2de7-43ac-97de-3c452f98a3c4; range_end:; response_count:1; response_revision:5296; }","duration":"242.069704ms","start":"2026-03-19T18:01:40.162124Z","end":"2026-03-19T18:01:40.404194Z","steps":["trace[2134048281] 'agreement among raft nodes before linearized reading' (duration: 241.798719ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:40.451227Z","caller":"traceutil/trace.go:171","msg":"trace[674013815] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"286.281537ms","start":"2026-03-19T18:01:40.164916Z","end":"2026-03-19T18:01:40.451197Z","steps":["trace[674013815] 'process raft request' (duration: 286.016792ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:40.451596Z","caller":"traceutil/trace.go:171","msg":"trace[1026123055] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"286.219404ms","start":"2026-03-19T18:01:40.165344Z","end":"2026-03-19T18:01:40.451563Z","steps":["trace[1026123055] 'process raft request' (duration: 285.800357ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:40.451718Z","caller":"traceutil/trace.go:171","msg":"trace[220243332] transaction","detail":"{read_only:false; response_revision:5299; number_of_response:1; }","duration":"285.97283ms","start":"2026-03-19T18:01:40.165725Z","end":"2026-03-19T18:01:40.451697Z","steps":["trace[220243332] 'process raft request' (duration: 285.663574ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:40.451894Z","caller":"traceutil/trace.go:171","msg":"trace[1674480093] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"285.498831ms","start":"2026-03-19T18:01:40.166342Z","end":"2026-03-19T18:01:40.451841Z","steps":["trace[1674480093] 'process raft request' (duration: 285.246657ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:40.452531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.275219ms","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:5"} {"level":"info","ts":"2026-03-19T18:01:40.452579Z","caller":"traceutil/trace.go:171","msg":"trace[12677139] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:5300; }","duration":"249.376511ms","start":"2026-03-19T18:01:40.203191Z","end":"2026-03-19T18:01:40.452568Z","steps":["trace[12677139] 'agreement among raft nodes before linearized reading' (duration: 249.275199ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:02:06.912402Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2777} {"level":"info","ts":"2026-03-19T18:02:06.971159Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2777,"took":"57.32765ms","hash":2106549095,"current-db-size-bytes":39919616,"current-db-size":"40 MB","current-db-size-in-use-bytes":29319168,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-19T18:02:06.97122Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2106549095,"revision":2777,"compact-revision":-1} {"level":"info","ts":"2026-03-19T18:02:14.437168Z","caller":"traceutil/trace.go:171","msg":"trace[2135728147] transaction","detail":"{read_only:false; response_revision:5671; number_of_response:1; }","duration":"167.379746ms","start":"2026-03-19T18:02:14.269757Z","end":"2026-03-19T18:02:14.437137Z","steps":["trace[2135728147] 'process raft request' (duration: 167.220163ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:02:14.48149Z","caller":"traceutil/trace.go:171","msg":"trace[1146729092] transaction","detail":"{read_only:false; response_revision:5672; number_of_response:1; }","duration":"119.660685ms","start":"2026-03-19T18:02:14.361796Z","end":"2026-03-19T18:02:14.481457Z","steps":["trace[1146729092] 'process raft request' (duration: 102.020843ms)","trace[1146729092] 'compare' (duration: 17.420228ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:07:06.923923Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5542} {"level":"info","ts":"2026-03-19T18:07:07.026702Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5542,"took":"100.039052ms","hash":3579388653,"current-db-size-bytes":41349120,"current-db-size":"41 MB","current-db-size-in-use-bytes":20549632,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-03-19T18:07:07.02676Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3579388653,"revision":5542,"compact-revision":2777} {"level":"info","ts":"2026-03-19T18:07:15.990611Z","caller":"traceutil/trace.go:171","msg":"trace[1891189884] transaction","detail":"{read_only:false; response_revision:7796; number_of_response:1; }","duration":"142.975017ms","start":"2026-03-19T18:07:15.84761Z","end":"2026-03-19T18:07:15.990585Z","steps":["trace[1891189884] 'process raft request' (duration: 142.819375ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:07:29.289455Z","caller":"traceutil/trace.go:171","msg":"trace[2086316649] linearizableReadLoop","detail":"{readStateIndex:8150; appliedIndex:8149; }","duration":"135.056721ms","start":"2026-03-19T18:07:29.154365Z","end":"2026-03-19T18:07:29.289422Z","steps":["trace[2086316649] 'read index received' (duration: 98.496821ms)","trace[2086316649] 'applied index is now lower than readState.Index' (duration: 36.55851ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:07:29.289614Z","caller":"traceutil/trace.go:171","msg":"trace[916912574] transaction","detail":"{read_only:false; response_revision:7860; number_of_response:1; }","duration":"135.337716ms","start":"2026-03-19T18:07:29.154244Z","end":"2026-03-19T18:07:29.289582Z","steps":["trace[916912574] 'process raft request' (duration: 98.663594ms)","trace[916912574] 'compare' (duration: 36.240914ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T18:07:29.289966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.57851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223811"} {"level":"info","ts":"2026-03-19T18:07:29.290037Z","caller":"traceutil/trace.go:171","msg":"trace[1754231790] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:7860; }","duration":"135.668332ms","start":"2026-03-19T18:07:29.154353Z","end":"2026-03-19T18:07:29.290021Z","steps":["trace[1754231790] 'agreement among raft nodes before linearized reading' (duration: 135.164612ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:07:29.679528Z","caller":"traceutil/trace.go:171","msg":"trace[1121311267] transaction","detail":"{read_only:false; response_revision:7861; number_of_response:1; }","duration":"106.853466ms","start":"2026-03-19T18:07:29.57264Z","end":"2026-03-19T18:07:29.679494Z","steps":["trace[1121311267] 'process raft request' (duration: 106.647342ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:09.044089Z","caller":"traceutil/trace.go:171","msg":"trace[777531098] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8144; }","duration":"151.139639ms","start":"2026-03-19T18:08:08.892919Z","end":"2026-03-19T18:08:09.044058Z","steps":["trace[777531098] 'process raft request' (duration: 122.265973ms)","trace[777531098] 'compare' (duration: 28.461899ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:08:09.197622Z","caller":"traceutil/trace.go:171","msg":"trace[43356442] transaction","detail":"{read_only:false; response_revision:8145; number_of_response:1; }","duration":"303.059744ms","start":"2026-03-19T18:08:08.894536Z","end":"2026-03-19T18:08:09.197595Z","steps":["trace[43356442] 'process raft request' (duration: 283.987929ms)","trace[43356442] 'compare' (duration: 18.929152ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:08:09.197748Z","caller":"traceutil/trace.go:171","msg":"trace[363600351] linearizableReadLoop","detail":"{readStateIndex:8449; appliedIndex:8448; }","duration":"117.883301ms","start":"2026-03-19T18:08:09.079846Z","end":"2026-03-19T18:08:09.197729Z","steps":["trace[363600351] 'read index received' (duration: 98.665254ms)","trace[363600351] 'applied index is now lower than readState.Index' (duration: 19.215747ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T18:08:09.197767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:08.894523Z","time spent":"303.169975ms","remote":"127.0.0.1:33830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4630,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T18:08:09.197887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.040334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-xz47t\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-03-19T18:08:09.197926Z","caller":"traceutil/trace.go:171","msg":"trace[1734655757] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-xz47t; range_end:; response_count:1; response_revision:8145; }","duration":"118.115255ms","start":"2026-03-19T18:08:09.0798Z","end":"2026-03-19T18:08:09.197915Z","steps":["trace[1734655757] 'agreement among raft nodes before linearized reading' (duration: 118.038484ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:09.198165Z","caller":"traceutil/trace.go:171","msg":"trace[1326110476] transaction","detail":"{read_only:false; response_revision:8146; number_of_response:1; }","duration":"117.201617ms","start":"2026-03-19T18:08:09.080935Z","end":"2026-03-19T18:08:09.198136Z","steps":["trace[1326110476] 'process raft request' (duration: 116.965213ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:09.198165Z","caller":"traceutil/trace.go:171","msg":"trace[1306037155] transaction","detail":"{read_only:false; response_revision:8147; number_of_response:1; }","duration":"110.439953ms","start":"2026-03-19T18:08:09.087699Z","end":"2026-03-19T18:08:09.198139Z","steps":["trace[1306037155] 'process raft request' (duration: 110.27621ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:10.126077Z","caller":"traceutil/trace.go:171","msg":"trace[1036568704] transaction","detail":"{read_only:false; response_revision:8154; number_of_response:1; }","duration":"261.159126ms","start":"2026-03-19T18:08:09.864886Z","end":"2026-03-19T18:08:10.126045Z","steps":["trace[1036568704] 'process raft request' (duration: 260.995773ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:10.163132Z","caller":"traceutil/trace.go:171","msg":"trace[1470029238] linearizableReadLoop","detail":"{readStateIndex:8459; appliedIndex:8458; }","duration":"233.057542ms","start":"2026-03-19T18:08:09.930046Z","end":"2026-03-19T18:08:10.163104Z","steps":["trace[1470029238] 'read index received' (duration: 196.000774ms)","trace[1470029238] 'applied index is now lower than readState.Index' (duration: 37.055768ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:08:10.163338Z","caller":"traceutil/trace.go:171","msg":"trace[643426110] transaction","detail":"{read_only:false; response_revision:8156; number_of_response:1; }","duration":"167.537534ms","start":"2026-03-19T18:08:09.995784Z","end":"2026-03-19T18:08:10.163321Z","steps":["trace[643426110] 'process raft request' (duration: 167.233209ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:10.163322Z","caller":"traceutil/trace.go:171","msg":"trace[715297372] transaction","detail":"{read_only:false; response_revision:8155; number_of_response:1; }","duration":"282.751437ms","start":"2026-03-19T18:08:09.880528Z","end":"2026-03-19T18:08:10.16328Z","steps":["trace[715297372] 'process raft request' (duration: 261.629833ms)","trace[715297372] 'compare' (duration: 20.730147ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T18:08:10.163371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.312907ms","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-03-19T18:08:10.163435Z","caller":"traceutil/trace.go:171","msg":"trace[1378633825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8157; }","duration":"233.423809ms","start":"2026-03-19T18:08:09.929991Z","end":"2026-03-19T18:08:10.163415Z","steps":["trace[1378633825] 'agreement among raft nodes before linearized reading' (duration: 233.256086ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:10.163521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.33764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.121\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-19T18:08:10.163559Z","caller":"traceutil/trace.go:171","msg":"trace[2126197696] range","detail":"{range_begin:/registry/masterleases/199.19.213.121; range_end:; response_count:1; response_revision:8157; }","duration":"118.407682ms","start":"2026-03-19T18:08:10.045139Z","end":"2026-03-19T18:08:10.163547Z","steps":["trace[2126197696] 'agreement among raft nodes before linearized reading' (duration: 118.32697ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:10.163538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.008126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-03-19T18:08:10.163614Z","caller":"traceutil/trace.go:171","msg":"trace[1476708865] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8157; }","duration":"152.122047ms","start":"2026-03-19T18:08:10.011472Z","end":"2026-03-19T18:08:10.163594Z","steps":["trace[1476708865] 'agreement among raft nodes before linearized reading' (duration: 151.926044ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:10.329099Z","caller":"traceutil/trace.go:171","msg":"trace[167399259] transaction","detail":"{read_only:false; response_revision:8161; number_of_response:1; }","duration":"111.88207ms","start":"2026-03-19T18:08:10.217177Z","end":"2026-03-19T18:08:10.329059Z","steps":["trace[167399259] 'process raft request' (duration: 111.697267ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:09:21.878614Z","caller":"traceutil/trace.go:171","msg":"trace[77496492] transaction","detail":"{read_only:false; response_revision:8843; number_of_response:1; }","duration":"111.714956ms","start":"2026-03-19T18:09:21.766854Z","end":"2026-03-19T18:09:21.878569Z","steps":["trace[77496492] 'process raft request' (duration: 111.422461ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:11:22.440333Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"31541ae44470b70","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-19T18:11:22.446187Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-03-19T18:11:22.446288Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-03-19T18:12:06.933637Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7617} {"level":"info","ts":"2026-03-19T18:12:06.995025Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7617,"took":"59.417245ms","hash":1066948068,"current-db-size-bytes":41349120,"current-db-size":"41 MB","current-db-size-in-use-bytes":25198592,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-19T18:12:06.995079Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1066948068,"revision":7617,"compact-revision":5542} {"level":"info","ts":"2026-03-19T18:17:06.944831Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9999} {"level":"info","ts":"2026-03-19T18:17:07.02494Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9999,"took":"77.991999ms","hash":3370522628,"current-db-size-bytes":41349120,"current-db-size":"41 MB","current-db-size-in-use-bytes":17793024,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-03-19T18:17:07.024991Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3370522628,"revision":9999,"compact-revision":7617}