{"level":"warn","ts":"2026-10-27T18:54:51.90543Z","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-27T18:54:51.905585Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.79: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.79:2380","--initial-cluster=instance=https://162.253.55.79:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.79:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.79: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-27T18:54:51.905889Z","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-27T18:54:51.905922Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.79:2380"]} {"level":"info","ts":"2026-10-27T18:54:51.905957Z","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-27T18:54:51.90698Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.79:2379"]} {"level":"info","ts":"2026-10-27T18:54:51.90711Z","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.79:2380"],"listen-peer-urls":["https://162.253.55.79:2380"],"advertise-client-urls":["https://162.253.55.79:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.79:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.79: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-27T18:54:51.910279Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.765015ms"} {"level":"info","ts":"2026-10-27T18:54:51.918378Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"401ca643156fc5da","cluster-id":"e9f568c3681bb0d0"} {"level":"info","ts":"2026-10-27T18:54:51.91855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da switched to configuration voters=()"} {"level":"info","ts":"2026-10-27T18:54:51.918597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became follower at term 0"} {"level":"info","ts":"2026-10-27T18:54:51.918619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 401ca643156fc5da [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-27T18:54:51.918643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became follower at term 1"} {"level":"info","ts":"2026-10-27T18:54:51.918708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da switched to configuration voters=(4619750124827952602)"} {"level":"warn","ts":"2026-10-27T18:54:51.931404Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-27T18:54:51.933831Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-27T18:54:51.935822Z","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-27T18:54:51.93951Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"401ca643156fc5da","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-27T18:54:51.939776Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"401ca643156fc5da","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-27T18:54:51.939884Z","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-27T18:54:51.940012Z","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-27T18:54:51.940025Z","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-27T18:54:51.942227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da switched to configuration voters=(4619750124827952602)"} {"level":"info","ts":"2026-10-27T18:54:51.942846Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e9f568c3681bb0d0","local-member-id":"401ca643156fc5da","added-peer-id":"401ca643156fc5da","added-peer-peer-urls":["https://162.253.55.79:2380"]} {"level":"info","ts":"2026-10-27T18:54:51.94558Z","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-27T18:54:51.945771Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.79:2380"} {"level":"info","ts":"2026-10-27T18:54:51.945888Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.79:2380"} {"level":"info","ts":"2026-10-27T18:54:51.946012Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"401ca643156fc5da","initial-advertise-peer-urls":["https://162.253.55.79:2380"],"listen-peer-urls":["https://162.253.55.79:2380"],"advertise-client-urls":["https://162.253.55.79:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.79:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-27T18:54:51.946082Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-27T18:54:52.419424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da is starting a new election at term 1"} {"level":"info","ts":"2026-10-27T18:54:52.419495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became pre-candidate at term 1"} {"level":"info","ts":"2026-10-27T18:54:52.41957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da received MsgPreVoteResp from 401ca643156fc5da at term 1"} {"level":"info","ts":"2026-10-27T18:54:52.41959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became candidate at term 2"} {"level":"info","ts":"2026-10-27T18:54:52.419605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da received MsgVoteResp from 401ca643156fc5da at term 2"} {"level":"info","ts":"2026-10-27T18:54:52.419621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became leader at term 2"} {"level":"info","ts":"2026-10-27T18:54:52.419639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 401ca643156fc5da elected leader 401ca643156fc5da at term 2"} {"level":"info","ts":"2026-10-27T18:54:52.441249Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"401ca643156fc5da","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.79:2379]}","request-path":"/0/members/401ca643156fc5da/attributes","cluster-id":"e9f568c3681bb0d0","publish-timeout":"7s"} {"level":"info","ts":"2026-10-27T18:54:52.441285Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T18:54:52.44137Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-27T18:54:52.441613Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-27T18:54:52.441483Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-27T18:54:52.441643Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-27T18:54:52.444407Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-27T18:54:52.445254Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e9f568c3681bb0d0","local-member-id":"401ca643156fc5da","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T18:54:52.445342Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.79:2379"} {"level":"info","ts":"2026-10-27T18:54:52.445407Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T18:54:52.445443Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T18:54:55.099397Z","caller":"traceutil/trace.go:171","msg":"trace[1822590886] linearizableReadLoop","detail":"{readStateIndex:118; appliedIndex:117; }","duration":"101.173806ms","start":"2026-10-27T18:54:54.998194Z","end":"2026-10-27T18:54:55.099368Z","steps":["trace[1822590886] 'read index received' (duration: 92.77924ms)","trace[1822590886] 'applied index is now lower than readState.Index' (duration: 8.393346ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T18:54:55.099447Z","caller":"traceutil/trace.go:171","msg":"trace[55318946] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"106.624083ms","start":"2026-10-27T18:54:54.992781Z","end":"2026-10-27T18:54:55.099405Z","steps":["trace[55318946] 'process raft request' (duration: 98.301769ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T18:54:55.099677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.161865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-27T18:54:55.099764Z","caller":"traceutil/trace.go:171","msg":"trace[185287063] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:113; }","duration":"101.31261ms","start":"2026-10-27T18:54:54.998436Z","end":"2026-10-27T18:54:55.099749Z","steps":["trace[185287063] 'agreement among raft nodes before linearized reading' (duration: 101.134285ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T18:54:55.099894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.635808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-27T18:54:55.10005Z","caller":"traceutil/trace.go:171","msg":"trace[460466030] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:113; }","duration":"101.841144ms","start":"2026-10-27T18:54:54.998156Z","end":"2026-10-27T18:54:55.099997Z","steps":["trace[460466030] 'agreement among raft nodes before linearized reading' (duration: 101.409812ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T18:55:18.758008Z","caller":"traceutil/trace.go:171","msg":"trace[1496054125] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"179.320261ms","start":"2026-10-27T18:55:18.578637Z","end":"2026-10-27T18:55:18.757957Z","steps":["trace[1496054125] 'process raft request' (duration: 179.157047ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T18:55:44.926227Z","caller":"traceutil/trace.go:171","msg":"trace[1442180780] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"336.38081ms","start":"2026-10-27T18:55:44.589765Z","end":"2026-10-27T18:55:44.926146Z","steps":["trace[1442180780] 'process raft request' (duration: 333.477426ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T18:55:44.92792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T18:55:44.589731Z","time spent":"337.098351ms","remote":"127.0.0.1:53424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T18:55:45.496664Z","caller":"traceutil/trace.go:171","msg":"trace[1083872817] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"471.648087ms","start":"2026-10-27T18:55:45.024962Z","end":"2026-10-27T18:55:45.49661Z","steps":["trace[1083872817] 'read index received' (duration: 471.584645ms)","trace[1083872817] 'applied index is now lower than readState.Index' (duration: 62.122µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T18:55:45.496781Z","caller":"traceutil/trace.go:171","msg":"trace[680719958] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"474.345696ms","start":"2026-10-27T18:55:45.022369Z","end":"2026-10-27T18:55:45.496714Z","steps":["trace[680719958] 'process raft request' (duration: 474.105359ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T18:55:45.49689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.910984ms","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-27T18:55:45.496935Z","caller":"traceutil/trace.go:171","msg":"trace[1305382706] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:654; }","duration":"472.011878ms","start":"2026-10-27T18:55:45.024912Z","end":"2026-10-27T18:55:45.496924Z","steps":["trace[1305382706] 'agreement among raft nodes before linearized reading' (duration: 471.820492ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T18:55:45.496968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T18:55:45.024894Z","time spent":"472.06665ms","remote":"127.0.0.1:53582","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-27T18:55:45.497013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T18:55:45.022348Z","time spent":"474.550191ms","remote":"127.0.0.1:53674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-27T18:55:45.600478Z","caller":"traceutil/trace.go:171","msg":"trace[1163298390] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"356.071683ms","start":"2026-10-27T18:55:45.244376Z","end":"2026-10-27T18:55:45.600448Z","steps":["trace[1163298390] 'process raft request' (duration: 328.002626ms)","trace[1163298390] 'compare' (duration: 27.845261ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T18:55:45.600861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T18:55:45.244338Z","time spent":"356.333751ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T18:55:47.187675Z","caller":"traceutil/trace.go:171","msg":"trace[813710087] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"137.541703ms","start":"2026-10-27T18:55:47.049191Z","end":"2026-10-27T18:55:47.186733Z","steps":["trace[813710087] 'process raft request' (duration: 130.973882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T18:55:47.187704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.287972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-10-27T18:55:47.18787Z","caller":"traceutil/trace.go:171","msg":"trace[971257358] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:676; }","duration":"106.53144ms","start":"2026-10-27T18:55:47.081307Z","end":"2026-10-27T18:55:47.187839Z","steps":["trace[971257358] 'agreement among raft nodes before linearized reading' (duration: 106.164449ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T18:55:47.187591Z","caller":"traceutil/trace.go:171","msg":"trace[897414633] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"106.086547ms","start":"2026-10-27T18:55:47.081314Z","end":"2026-10-27T18:55:47.187401Z","steps":["trace[897414633] 'read index received' (duration: 98.574439ms)","trace[897414633] 'applied index is now lower than readState.Index' (duration: 7.507328ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T18:56:00.772458Z","caller":"traceutil/trace.go:171","msg":"trace[1102256612] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:852; }","duration":"161.584667ms","start":"2026-10-27T18:56:00.610855Z","end":"2026-10-27T18:56:00.77244Z","steps":["trace[1102256612] 'read index received' (duration: 161.536076ms)","trace[1102256612] 'applied index is now lower than readState.Index' (duration: 47.591µs)"],"step_count":2} {"level":"warn","ts":"2026-10-27T18:56:00.77262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.744941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-27T18:56:00.77264Z","caller":"traceutil/trace.go:171","msg":"trace[766704315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:823; }","duration":"161.803063ms","start":"2026-10-27T18:56:00.61083Z","end":"2026-10-27T18:56:00.772633Z","steps":["trace[766704315] 'agreement among raft nodes before linearized reading' (duration: 161.68878ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T18:56:00.772767Z","caller":"traceutil/trace.go:171","msg":"trace[853150227] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"162.128432ms","start":"2026-10-27T18:56:00.610633Z","end":"2026-10-27T18:56:00.772762Z","steps":["trace[853150227] 'process raft request' (duration: 161.710911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T18:57:16.282622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.048799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-27T18:57:16.282764Z","caller":"traceutil/trace.go:171","msg":"trace[1595594776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1881; }","duration":"147.234104ms","start":"2026-10-27T18:57:16.135497Z","end":"2026-10-27T18:57:16.282731Z","steps":["trace[1595594776] 'range keys from in-memory index tree' (duration: 146.72202ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T18:57:16.282619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.020292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.79\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-10-27T18:57:16.282822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.22633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c6415bfd-a8cf-45d1-b9d2-f1039f87218f\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-27T18:57:16.282893Z","caller":"traceutil/trace.go:171","msg":"trace[984723864] range","detail":"{range_begin:/registry/masterleases/162.253.55.79; range_end:; response_count:1; response_revision:1881; }","duration":"268.380972ms","start":"2026-10-27T18:57:16.014476Z","end":"2026-10-27T18:57:16.282857Z","steps":["trace[984723864] 'range keys from in-memory index tree' (duration: 267.741615ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T18:57:16.282953Z","caller":"traceutil/trace.go:171","msg":"trace[792152171] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c6415bfd-a8cf-45d1-b9d2-f1039f87218f; range_end:; response_count:1; response_revision:1881; }","duration":"240.487188ms","start":"2026-10-27T18:57:16.042443Z","end":"2026-10-27T18:57:16.28293Z","steps":["trace[792152171] 'range keys from in-memory index tree' (duration: 240.026535ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T18:57:16.282842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.159967ms","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-27T18:57:16.283112Z","caller":"traceutil/trace.go:171","msg":"trace[1829933111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1881; }","duration":"152.516358ms","start":"2026-10-27T18:57:16.130565Z","end":"2026-10-27T18:57:16.283081Z","steps":["trace[1829933111] 'range keys from in-memory index tree' (duration: 151.906481ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T18:57:16.682099Z","caller":"traceutil/trace.go:171","msg":"trace[1338733208] linearizableReadLoop","detail":"{readStateIndex:1960; appliedIndex:1957; }","duration":"328.180771ms","start":"2026-10-27T18:57:16.353896Z","end":"2026-10-27T18:57:16.682077Z","steps":["trace[1338733208] 'read index received' (duration: 17.00946ms)","trace[1338733208] 'applied index is now lower than readState.Index' (duration: 311.170121ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T18:57:16.682154Z","caller":"traceutil/trace.go:171","msg":"trace[780301070] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"365.439529ms","start":"2026-10-27T18:57:16.316688Z","end":"2026-10-27T18:57:16.682128Z","steps":["trace[780301070] 'process raft request' (duration: 365.241954ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T18:57:16.682292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T18:57:16.316667Z","time spent":"365.532372ms","remote":"127.0.0.1:53366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T18:57:16.682607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.666494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-27T18:57:16.682924Z","caller":"traceutil/trace.go:171","msg":"trace[392463375] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1884; }","duration":"328.850709ms","start":"2026-10-27T18:57:16.353846Z","end":"2026-10-27T18:57:16.682697Z","steps":["trace[392463375] 'agreement among raft nodes before linearized reading' (duration: 328.299424ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T18:57:16.683226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T18:57:16.353819Z","time spent":"329.214928ms","remote":"127.0.0.1:53692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-10-27T18:57:16.700143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.888762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-10-27T18:57:16.700114Z","caller":"traceutil/trace.go:171","msg":"trace[205044388] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"106.212343ms","start":"2026-10-27T18:57:16.593863Z","end":"2026-10-27T18:57:16.700075Z","steps":["trace[205044388] 'process raft request' (duration: 106.031228ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T18:57:16.700206Z","caller":"traceutil/trace.go:171","msg":"trace[849533327] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1885; }","duration":"188.993524ms","start":"2026-10-27T18:57:16.511195Z","end":"2026-10-27T18:57:16.700189Z","steps":["trace[849533327] 'agreement among raft nodes before linearized reading' (duration: 188.82819ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T18:57:16.70027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.641959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c6415bfd-a8cf-45d1-b9d2-f1039f87218f\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-27T18:57:16.700322Z","caller":"traceutil/trace.go:171","msg":"trace[107716648] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c6415bfd-a8cf-45d1-b9d2-f1039f87218f; range_end:; response_count:1; response_revision:1885; }","duration":"275.747433ms","start":"2026-10-27T18:57:16.424562Z","end":"2026-10-27T18:57:16.700309Z","steps":["trace[107716648] 'agreement among raft nodes before linearized reading' (duration: 275.587577ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T18:57:30.55385Z","caller":"traceutil/trace.go:171","msg":"trace[1025531198] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"113.235845ms","start":"2026-10-27T18:57:30.440587Z","end":"2026-10-27T18:57:30.553823Z","steps":["trace[1025531198] 'process raft request' (duration: 113.007719ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T18:57:30.582017Z","caller":"traceutil/trace.go:171","msg":"trace[97892418] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"139.260967ms","start":"2026-10-27T18:57:30.442734Z","end":"2026-10-27T18:57:30.581995Z","steps":["trace[97892418] 'process raft request' (duration: 139.157074ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T18:57:55.900775Z","caller":"traceutil/trace.go:171","msg":"trace[2048833385] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"113.530163ms","start":"2026-10-27T18:57:55.787222Z","end":"2026-10-27T18:57:55.900752Z","steps":["trace[2048833385] 'process raft request' (duration: 113.414949ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T18:58:01.591811Z","caller":"traceutil/trace.go:171","msg":"trace[205605650] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"171.592397ms","start":"2026-10-27T18:58:01.420192Z","end":"2026-10-27T18:58:01.591785Z","steps":["trace[205605650] 'process raft request' (duration: 94.973231ms)","trace[205605650] 'compare' (duration: 76.486723ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T18:58:02.118666Z","caller":"traceutil/trace.go:171","msg":"trace[1873257917] linearizableReadLoop","detail":"{readStateIndex:2277; appliedIndex:2276; }","duration":"192.372247ms","start":"2026-10-27T18:58:01.926266Z","end":"2026-10-27T18:58:02.118639Z","steps":["trace[1873257917] 'read index received' (duration: 192.194343ms)","trace[1873257917] 'applied index is now lower than readState.Index' (duration: 176.874µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T18:58:02.118745Z","caller":"traceutil/trace.go:171","msg":"trace[901626798] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"247.004421ms","start":"2026-10-27T18:58:01.87171Z","end":"2026-10-27T18:58:02.118714Z","steps":["trace[901626798] 'process raft request' (duration: 246.760465ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T18:58:02.118956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.675666ms","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-27T18:58:02.119015Z","caller":"traceutil/trace.go:171","msg":"trace[334720657] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2190; }","duration":"192.806189ms","start":"2026-10-27T18:58:01.926192Z","end":"2026-10-27T18:58:02.118998Z","steps":["trace[334720657] 'agreement among raft nodes before linearized reading' (duration: 192.599093ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T18:58:07.363187Z","caller":"traceutil/trace.go:171","msg":"trace[2036295661] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"153.51378ms","start":"2026-10-27T18:58:07.209641Z","end":"2026-10-27T18:58:07.363154Z","steps":["trace[2036295661] 'process raft request' (duration: 153.305715ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T18:58:57.130844Z","caller":"traceutil/trace.go:171","msg":"trace[380054358] transaction","detail":"{read_only:false; response_revision:2660; number_of_response:1; }","duration":"132.413477ms","start":"2026-10-27T18:58:56.998409Z","end":"2026-10-27T18:58:57.130823Z","steps":["trace[380054358] 'process raft request' (duration: 132.289433ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T18:58:57.146904Z","caller":"traceutil/trace.go:171","msg":"trace[675401627] transaction","detail":"{read_only:false; response_revision:2661; number_of_response:1; }","duration":"148.392888ms","start":"2026-10-27T18:58:56.998479Z","end":"2026-10-27T18:58:57.146872Z","steps":["trace[675401627] 'process raft request' (duration: 147.323279ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T18:58:57.146871Z","caller":"traceutil/trace.go:171","msg":"trace[122526408] linearizableReadLoop","detail":"{readStateIndex:2765; appliedIndex:2763; }","duration":"124.246778ms","start":"2026-10-27T18:58:57.022603Z","end":"2026-10-27T18:58:57.14685Z","steps":["trace[122526408] 'read index received' (duration: 108.185705ms)","trace[122526408] 'applied index is now lower than readState.Index' (duration: 16.055652ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T18:58:57.147017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.395812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T18:58:57.147068Z","caller":"traceutil/trace.go:171","msg":"trace[1672921745] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:2662; }","duration":"124.502055ms","start":"2026-10-27T18:58:57.022554Z","end":"2026-10-27T18:58:57.147056Z","steps":["trace[1672921745] 'agreement among raft nodes before linearized reading' (duration: 124.388802ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T18:59:30.611407Z","caller":"traceutil/trace.go:171","msg":"trace[928677074] transaction","detail":"{read_only:false; response_revision:2862; number_of_response:1; }","duration":"118.597446ms","start":"2026-10-27T18:59:30.492777Z","end":"2026-10-27T18:59:30.611375Z","steps":["trace[928677074] 'process raft request' (duration: 118.333779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T18:59:30.930558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.722461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T18:59:30.930678Z","caller":"traceutil/trace.go:171","msg":"trace[711801328] linearizableReadLoop","detail":"{readStateIndex:2974; appliedIndex:2973; }","duration":"246.983964ms","start":"2026-10-27T18:59:30.683675Z","end":"2026-10-27T18:59:30.930659Z","steps":["trace[711801328] 'read index received' (duration: 79.913524ms)","trace[711801328] 'applied index is now lower than readState.Index' (duration: 167.06914ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T18:59:30.930777Z","caller":"traceutil/trace.go:171","msg":"trace[1998778594] transaction","detail":"{read_only:false; response_revision:2863; number_of_response:1; }","duration":"315.053697ms","start":"2026-10-27T18:59:30.615708Z","end":"2026-10-27T18:59:30.930761Z","steps":["trace[1998778594] 'process raft request' (duration: 147.867083ms)","trace[1998778594] 'compare' (duration: 166.413413ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T18:59:30.930982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T18:59:30.615685Z","time spent":"315.127209ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T18:59:30.931057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.004451ms","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":"warn","ts":"2026-10-27T18:59:30.931146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.460006ms","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-27T18:59:30.931179Z","caller":"traceutil/trace.go:171","msg":"trace[618716265] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2863; }","duration":"225.180676ms","start":"2026-10-27T18:59:30.705969Z","end":"2026-10-27T18:59:30.93115Z","steps":["trace[618716265] 'agreement among raft nodes before linearized reading' (duration: 224.991421ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T18:59:30.931216Z","caller":"traceutil/trace.go:171","msg":"trace[2107602362] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2863; }","duration":"247.574029ms","start":"2026-10-27T18:59:30.683625Z","end":"2026-10-27T18:59:30.931199Z","steps":["trace[2107602362] 'agreement among raft nodes before linearized reading' (duration: 247.385734ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T18:59:31.044899Z","caller":"traceutil/trace.go:171","msg":"trace[62603504] transaction","detail":"{read_only:false; response_revision:2864; number_of_response:1; }","duration":"108.924425ms","start":"2026-10-27T18:59:30.935946Z","end":"2026-10-27T18:59:31.04487Z","steps":["trace[62603504] 'process raft request' (duration: 106.292214ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:02:39.220932Z","caller":"traceutil/trace.go:171","msg":"trace[1758107160] linearizableReadLoop","detail":"{readStateIndex:4215; appliedIndex:4214; }","duration":"161.232435ms","start":"2026-10-27T19:02:39.059675Z","end":"2026-10-27T19:02:39.220907Z","steps":["trace[1758107160] 'read index received' (duration: 161.029819ms)","trace[1758107160] 'applied index is now lower than readState.Index' (duration: 201.516µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:02:39.220986Z","caller":"traceutil/trace.go:171","msg":"trace[421437188] transaction","detail":"{read_only:false; response_revision:4059; number_of_response:1; }","duration":"162.766226ms","start":"2026-10-27T19:02:39.058185Z","end":"2026-10-27T19:02:39.220951Z","steps":["trace[421437188] 'process raft request' (duration: 162.585281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:02:39.221381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.640715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-27T19:02:39.221473Z","caller":"traceutil/trace.go:171","msg":"trace[2055949690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4059; }","duration":"161.844681ms","start":"2026-10-27T19:02:39.05961Z","end":"2026-10-27T19:02:39.221455Z","steps":["trace[2055949690] 'agreement among raft nodes before linearized reading' (duration: 161.43993ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:04:06.944369Z","caller":"traceutil/trace.go:171","msg":"trace[1596573039] linearizableReadLoop","detail":"{readStateIndex:5456; appliedIndex:5455; }","duration":"145.11297ms","start":"2026-10-27T19:04:06.799218Z","end":"2026-10-27T19:04:06.944331Z","steps":["trace[1596573039] 'read index received' (duration: 144.819923ms)","trace[1596573039] 'applied index is now lower than readState.Index' (duration: 291.467µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:04:06.944405Z","caller":"traceutil/trace.go:171","msg":"trace[1565503404] transaction","detail":"{read_only:false; response_revision:5259; number_of_response:1; }","duration":"147.056162ms","start":"2026-10-27T19:04:06.797305Z","end":"2026-10-27T19:04:06.944361Z","steps":["trace[1565503404] 'process raft request' (duration: 146.766144ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:04:06.944618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.372987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-wvdbz-tls\" ","response":"range_response_count:1 size:4427"} {"level":"info","ts":"2026-10-27T19:04:06.944654Z","caller":"traceutil/trace.go:171","msg":"trace[1723006841] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-wvdbz-tls; range_end:; response_count:1; response_revision:5259; }","duration":"145.48216ms","start":"2026-10-27T19:04:06.799165Z","end":"2026-10-27T19:04:06.944647Z","steps":["trace[1723006841] 'agreement among raft nodes before linearized reading' (duration: 145.312805ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:04:06.944801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.599138ms","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-27T19:04:06.944858Z","caller":"traceutil/trace.go:171","msg":"trace[1658752553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5259; }","duration":"119.719351ms","start":"2026-10-27T19:04:06.825123Z","end":"2026-10-27T19:04:06.944843Z","steps":["trace[1658752553] 'agreement among raft nodes before linearized reading' (duration: 119.490375ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:04:06.945347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.68833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mr926\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-27T19:04:06.94538Z","caller":"traceutil/trace.go:171","msg":"trace[910071028] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mr926; range_end:; response_count:1; response_revision:5259; }","duration":"122.740952ms","start":"2026-10-27T19:04:06.822628Z","end":"2026-10-27T19:04:06.945369Z","steps":["trace[910071028] 'agreement among raft nodes before linearized reading' (duration: 121.92091ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:04:07.184469Z","caller":"traceutil/trace.go:171","msg":"trace[1399753705] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"231.434638ms","start":"2026-10-27T19:04:06.952947Z","end":"2026-10-27T19:04:07.184382Z","steps":["trace[1399753705] 'process raft request' (duration: 210.149649ms)","trace[1399753705] 'compare' (duration: 20.889408ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:04:07.184496Z","caller":"traceutil/trace.go:171","msg":"trace[1198822595] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"175.826911ms","start":"2026-10-27T19:04:07.008631Z","end":"2026-10-27T19:04:07.184458Z","steps":["trace[1198822595] 'process raft request' (duration: 175.644145ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:04:07.184745Z","caller":"traceutil/trace.go:171","msg":"trace[1565611002] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"166.631585ms","start":"2026-10-27T19:04:07.018063Z","end":"2026-10-27T19:04:07.184695Z","steps":["trace[1565611002] 'process raft request' (duration: 166.350328ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:04:07.184842Z","caller":"traceutil/trace.go:171","msg":"trace[1843414561] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"111.603503ms","start":"2026-10-27T19:04:07.073202Z","end":"2026-10-27T19:04:07.184806Z","steps":["trace[1843414561] 'process raft request' (duration: 111.349617ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:04:20.500185Z","caller":"traceutil/trace.go:171","msg":"trace[1542430871] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"153.418172ms","start":"2026-10-27T19:04:20.346706Z","end":"2026-10-27T19:04:20.500124Z","steps":["trace[1542430871] 'process raft request' (duration: 139.855189ms)","trace[1542430871] 'compare' (duration: 13.204323ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:04:28.037206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.692773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T19:04:28.037455Z","caller":"traceutil/trace.go:171","msg":"trace[1327357042] transaction","detail":"{read_only:false; response_revision:5659; number_of_response:1; }","duration":"154.572172ms","start":"2026-10-27T19:04:27.88283Z","end":"2026-10-27T19:04:28.037402Z","steps":["trace[1327357042] 'process raft request' (duration: 29.571261ms)","trace[1327357042] 'compare' (duration: 124.550389ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:04:40.130138Z","caller":"traceutil/trace.go:171","msg":"trace[239921590] transaction","detail":"{read_only:false; response_revision:5827; number_of_response:1; }","duration":"139.247283ms","start":"2026-10-27T19:04:39.990833Z","end":"2026-10-27T19:04:40.130081Z","steps":["trace[239921590] 'process raft request' (duration: 99.148771ms)","trace[239921590] 'compare' (duration: 39.874896ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:04:40.130378Z","caller":"traceutil/trace.go:171","msg":"trace[1354954744] transaction","detail":"{read_only:false; response_revision:5828; number_of_response:1; }","duration":"139.325346ms","start":"2026-10-27T19:04:39.991014Z","end":"2026-10-27T19:04:40.13034Z","steps":["trace[1354954744] 'process raft request' (duration: 139.023258ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:04:40.130595Z","caller":"traceutil/trace.go:171","msg":"trace[1059871162] transaction","detail":"{read_only:false; response_revision:5829; number_of_response:1; }","duration":"139.120869ms","start":"2026-10-27T19:04:39.991463Z","end":"2026-10-27T19:04:40.130584Z","steps":["trace[1059871162] 'process raft request' (duration: 138.78905ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:04:40.13074Z","caller":"traceutil/trace.go:171","msg":"trace[255093395] linearizableReadLoop","detail":"{readStateIndex:6041; appliedIndex:6037; }","duration":"136.932941ms","start":"2026-10-27T19:04:39.993778Z","end":"2026-10-27T19:04:40.130711Z","steps":["trace[255093395] 'read index received' (duration: 13.002628ms)","trace[255093395] 'applied index is now lower than readState.Index' (duration: 123.926173ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:04:40.130977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.188408ms","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-10-27T19:04:40.131029Z","caller":"traceutil/trace.go:171","msg":"trace[1706964338] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5830; }","duration":"137.29278ms","start":"2026-10-27T19:04:39.993724Z","end":"2026-10-27T19:04:40.131016Z","steps":["trace[1706964338] 'agreement among raft nodes before linearized reading' (duration: 137.109726ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:04:52.506355Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2986} {"level":"info","ts":"2026-10-27T19:04:52.629309Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2986,"took":"121.331323ms","hash":3270438889,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":32292864,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-27T19:04:52.62938Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3270438889,"revision":2986,"compact-revision":-1} {"level":"info","ts":"2026-10-27T19:09:52.513215Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5914} {"level":"info","ts":"2026-10-27T19:09:52.605426Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5914,"took":"90.352116ms","hash":1034453742,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":26607616,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-27T19:09:52.60549Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1034453742,"revision":5914,"compact-revision":2986} {"level":"info","ts":"2026-10-27T19:10:31.880939Z","caller":"traceutil/trace.go:171","msg":"trace[1665997313] linearizableReadLoop","detail":"{readStateIndex:8780; appliedIndex:8779; }","duration":"147.724497ms","start":"2026-10-27T19:10:31.733184Z","end":"2026-10-27T19:10:31.880908Z","steps":["trace[1665997313] 'read index received' (duration: 147.480601ms)","trace[1665997313] 'applied index is now lower than readState.Index' (duration: 242.326µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:10:31.880981Z","caller":"traceutil/trace.go:171","msg":"trace[1769118905] transaction","detail":"{read_only:false; response_revision:8482; number_of_response:1; }","duration":"172.946865ms","start":"2026-10-27T19:10:31.707999Z","end":"2026-10-27T19:10:31.880946Z","steps":["trace[1769118905] 'process raft request' (duration: 172.689358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:10:31.881206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.985255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T19:10:31.881386Z","caller":"traceutil/trace.go:171","msg":"trace[408597826] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:8482; }","duration":"148.222991ms","start":"2026-10-27T19:10:31.733129Z","end":"2026-10-27T19:10:31.881352Z","steps":["trace[408597826] 'agreement among raft nodes before linearized reading' (duration: 147.948323ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:10:32.144298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.045684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T19:10:32.144415Z","caller":"traceutil/trace.go:171","msg":"trace[67797963] linearizableReadLoop","detail":"{readStateIndex:8781; appliedIndex:8780; }","duration":"263.352114ms","start":"2026-10-27T19:10:31.881047Z","end":"2026-10-27T19:10:32.144399Z","steps":["trace[67797963] 'read index received' (duration: 92.087973ms)","trace[67797963] 'applied index is now lower than readState.Index' (duration: 171.262431ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:10:32.144601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.33991ms","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-27T19:10:32.14456Z","caller":"traceutil/trace.go:171","msg":"trace[1624296265] transaction","detail":"{read_only:false; response_revision:8483; number_of_response:1; }","duration":"274.049621ms","start":"2026-10-27T19:10:31.87047Z","end":"2026-10-27T19:10:32.14452Z","steps":["trace[1624296265] 'process raft request' (duration: 102.688328ms)","trace[1624296265] 'compare' (duration: 170.465469ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:10:32.144684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.943365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"warn","ts":"2026-10-27T19:10:32.144687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.730141ms","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-27T19:10:32.144656Z","caller":"traceutil/trace.go:171","msg":"trace[253925832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8483; }","duration":"229.408492ms","start":"2026-10-27T19:10:31.915215Z","end":"2026-10-27T19:10:32.144623Z","steps":["trace[253925832] 'agreement among raft nodes before linearized reading' (duration: 229.282448ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:10:32.144762Z","caller":"traceutil/trace.go:171","msg":"trace[1603583546] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:8483; }","duration":"361.062528ms","start":"2026-10-27T19:10:31.783683Z","end":"2026-10-27T19:10:32.144745Z","steps":["trace[1603583546] 'agreement among raft nodes before linearized reading' (duration: 360.802881ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:10:32.144793Z","caller":"traceutil/trace.go:171","msg":"trace[1693845692] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:8483; }","duration":"226.914246ms","start":"2026-10-27T19:10:31.917851Z","end":"2026-10-27T19:10:32.144766Z","steps":["trace[1693845692] 'agreement among raft nodes before linearized reading' (duration: 226.70952ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:10:32.144802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:10:31.783669Z","time spent":"361.121179ms","remote":"127.0.0.1:53474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1656,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-10-27T19:10:43.701673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.965043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-27T19:10:43.701821Z","caller":"traceutil/trace.go:171","msg":"trace[1000695712] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8544; }","duration":"276.196489ms","start":"2026-10-27T19:10:43.425594Z","end":"2026-10-27T19:10:43.70179Z","steps":["trace[1000695712] 'count revisions from in-memory index tree' (duration: 275.84678ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:10:43.701854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.946701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mr926\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-27T19:10:43.70194Z","caller":"traceutil/trace.go:171","msg":"trace[653515750] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mr926; range_end:; response_count:1; response_revision:8544; }","duration":"302.124096ms","start":"2026-10-27T19:10:43.399793Z","end":"2026-10-27T19:10:43.701917Z","steps":["trace[653515750] 'range keys from in-memory index tree' (duration: 301.694784ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:10:43.701994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:10:43.399768Z","time spent":"302.216617ms","remote":"127.0.0.1:53498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-mr926\" "} {"level":"info","ts":"2026-10-27T19:10:44.305081Z","caller":"traceutil/trace.go:171","msg":"trace[500491110] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"120.533118ms","start":"2026-10-27T19:10:44.184501Z","end":"2026-10-27T19:10:44.305035Z","steps":["trace[500491110] 'process raft request' (duration: 98.898688ms)","trace[500491110] 'compare' (duration: 21.358093ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:10:44.619984Z","caller":"traceutil/trace.go:171","msg":"trace[148218804] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"309.402431ms","start":"2026-10-27T19:10:44.310541Z","end":"2026-10-27T19:10:44.619943Z","steps":["trace[148218804] 'process raft request' (duration: 276.391024ms)","trace[148218804] 'compare' (duration: 32.848423ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:10:44.620143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:10:44.310516Z","time spent":"309.540765ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T19:10:45.298843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.467549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T19:10:45.298931Z","caller":"traceutil/trace.go:171","msg":"trace[2028232504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8553; }","duration":"100.616053ms","start":"2026-10-27T19:10:45.198294Z","end":"2026-10-27T19:10:45.29891Z","steps":["trace[2028232504] 'range keys from in-memory index tree' (duration: 100.362346ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:11:13.823524Z","caller":"traceutil/trace.go:171","msg":"trace[2060496517] linearizableReadLoop","detail":"{readStateIndex:9071; appliedIndex:9070; }","duration":"206.359583ms","start":"2026-10-27T19:11:13.617119Z","end":"2026-10-27T19:11:13.823479Z","steps":["trace[2060496517] 'read index received' (duration: 206.129157ms)","trace[2060496517] 'applied index is now lower than readState.Index' (duration: 229.366µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:11:13.823564Z","caller":"traceutil/trace.go:171","msg":"trace[1147893148] transaction","detail":"{read_only:false; response_revision:8762; number_of_response:1; }","duration":"350.061743ms","start":"2026-10-27T19:11:13.47347Z","end":"2026-10-27T19:11:13.823532Z","steps":["trace[1147893148] 'process raft request' (duration: 349.856808ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:11:13.823763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:11:13.473445Z","time spent":"350.216097ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T19:11:13.823791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.6684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"info","ts":"2026-10-27T19:11:13.823822Z","caller":"traceutil/trace.go:171","msg":"trace[1424336922] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8762; }","duration":"206.751283ms","start":"2026-10-27T19:11:13.617061Z","end":"2026-10-27T19:11:13.823813Z","steps":["trace[1424336922] 'agreement among raft nodes before linearized reading' (duration: 206.563738ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:11:14.082414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.932965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T19:11:14.082521Z","caller":"traceutil/trace.go:171","msg":"trace[878595291] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8762; }","duration":"176.103391ms","start":"2026-10-27T19:11:13.906393Z","end":"2026-10-27T19:11:14.082496Z","steps":["trace[878595291] 'range keys from in-memory index tree' (duration: 175.816683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:11:14.082986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.045257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mr926\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-27T19:11:14.083086Z","caller":"traceutil/trace.go:171","msg":"trace[621315955] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mr926; range_end:; response_count:1; response_revision:8762; }","duration":"202.171871ms","start":"2026-10-27T19:11:13.880888Z","end":"2026-10-27T19:11:14.08306Z","steps":["trace[621315955] 'range keys from in-memory index tree' (duration: 201.880883ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:11:20.076753Z","caller":"traceutil/trace.go:171","msg":"trace[501007329] linearizableReadLoop","detail":"{readStateIndex:9105; appliedIndex:9104; }","duration":"102.00051ms","start":"2026-10-27T19:11:19.974731Z","end":"2026-10-27T19:11:20.076731Z","steps":["trace[501007329] 'read index received' (duration: 101.796644ms)","trace[501007329] 'applied index is now lower than readState.Index' (duration: 202.926µs)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:11:20.076907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.148654ms","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-27T19:11:20.076938Z","caller":"traceutil/trace.go:171","msg":"trace[362532979] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:8795; }","duration":"102.222956ms","start":"2026-10-27T19:11:19.974705Z","end":"2026-10-27T19:11:20.076928Z","steps":["trace[362532979] 'agreement among raft nodes before linearized reading' (duration: 102.118744ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:11:20.077196Z","caller":"traceutil/trace.go:171","msg":"trace[1604254275] transaction","detail":"{read_only:false; response_revision:8795; number_of_response:1; }","duration":"186.477829ms","start":"2026-10-27T19:11:19.890677Z","end":"2026-10-27T19:11:20.077155Z","steps":["trace[1604254275] 'process raft request' (duration: 185.936775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:11:20.296736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.289719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-27T19:11:20.296822Z","caller":"traceutil/trace.go:171","msg":"trace[1984707043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8795; }","duration":"105.422582ms","start":"2026-10-27T19:11:20.19138Z","end":"2026-10-27T19:11:20.296802Z","steps":["trace[1984707043] 'range keys from in-memory index tree' (duration: 105.101593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:11:20.297263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.07686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:229871"} {"level":"info","ts":"2026-10-27T19:11:20.297341Z","caller":"traceutil/trace.go:171","msg":"trace[2064306981] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8795; }","duration":"163.313477ms","start":"2026-10-27T19:11:20.134009Z","end":"2026-10-27T19:11:20.297323Z","steps":["trace[2064306981] 'range keys from in-memory index tree' (duration: 162.605968ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:11:20.959821Z","caller":"traceutil/trace.go:171","msg":"trace[701907288] transaction","detail":"{read_only:false; response_revision:8800; number_of_response:1; }","duration":"104.162278ms","start":"2026-10-27T19:11:20.855627Z","end":"2026-10-27T19:11:20.959789Z","steps":["trace[701907288] 'process raft request' (duration: 103.816679ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:11:27.728998Z","caller":"traceutil/trace.go:171","msg":"trace[2073380327] transaction","detail":"{read_only:false; response_revision:8879; number_of_response:1; }","duration":"130.142726ms","start":"2026-10-27T19:11:27.59883Z","end":"2026-10-27T19:11:27.728973Z","steps":["trace[2073380327] 'process raft request' (duration: 130.085164ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:11:27.729019Z","caller":"traceutil/trace.go:171","msg":"trace[1767579190] transaction","detail":"{read_only:false; response_revision:8878; number_of_response:1; }","duration":"133.401893ms","start":"2026-10-27T19:11:27.595582Z","end":"2026-10-27T19:11:27.728984Z","steps":["trace[1767579190] 'process raft request' (duration: 133.183197ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:11:28.58413Z","caller":"traceutil/trace.go:171","msg":"trace[1818161983] linearizableReadLoop","detail":"{readStateIndex:9199; appliedIndex:9198; }","duration":"235.988721ms","start":"2026-10-27T19:11:28.348121Z","end":"2026-10-27T19:11:28.58411Z","steps":["trace[1818161983] 'read index received' (duration: 235.93515ms)","trace[1818161983] 'applied index is now lower than readState.Index' (duration: 52.581µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:11:28.584216Z","caller":"traceutil/trace.go:171","msg":"trace[1120207130] transaction","detail":"{read_only:false; response_revision:8883; number_of_response:1; }","duration":"236.750462ms","start":"2026-10-27T19:11:28.347432Z","end":"2026-10-27T19:11:28.584183Z","steps":["trace[1120207130] 'process raft request' (duration: 236.559207ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:11:28.584641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.560849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:229737"} {"level":"info","ts":"2026-10-27T19:11:28.584687Z","caller":"traceutil/trace.go:171","msg":"trace[768697293] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8883; }","duration":"245.62066ms","start":"2026-10-27T19:11:28.339056Z","end":"2026-10-27T19:11:28.584676Z","steps":["trace[768697293] 'agreement among raft nodes before linearized reading' (duration: 245.210808ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:11:28.646786Z","caller":"traceutil/trace.go:171","msg":"trace[530224708] transaction","detail":"{read_only:false; response_revision:8884; number_of_response:1; }","duration":"129.737306ms","start":"2026-10-27T19:11:28.517013Z","end":"2026-10-27T19:11:28.64675Z","steps":["trace[530224708] 'process raft request' (duration: 129.474639ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:11:28.646868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.44242ms","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-27T19:11:28.646928Z","caller":"traceutil/trace.go:171","msg":"trace[835631619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8884; }","duration":"281.518802ms","start":"2026-10-27T19:11:28.365392Z","end":"2026-10-27T19:11:28.646911Z","steps":["trace[835631619] 'agreement among raft nodes before linearized reading' (duration: 281.278315ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:11:28.646953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.954306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T19:11:28.647323Z","caller":"traceutil/trace.go:171","msg":"trace[335225379] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:8884; }","duration":"225.097439ms","start":"2026-10-27T19:11:28.421943Z","end":"2026-10-27T19:11:28.64704Z","steps":["trace[335225379] 'agreement among raft nodes before linearized reading' (duration: 224.839232ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:11:28.822256Z","caller":"traceutil/trace.go:171","msg":"trace[1086799019] linearizableReadLoop","detail":"{readStateIndex:9201; appliedIndex:9200; }","duration":"146.592697ms","start":"2026-10-27T19:11:28.675639Z","end":"2026-10-27T19:11:28.822232Z","steps":["trace[1086799019] 'read index received' (duration: 146.364201ms)","trace[1086799019] 'applied index is now lower than readState.Index' (duration: 227.446µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:11:28.822306Z","caller":"traceutil/trace.go:171","msg":"trace[867857881] transaction","detail":"{read_only:false; response_revision:8885; number_of_response:1; }","duration":"170.922799ms","start":"2026-10-27T19:11:28.651353Z","end":"2026-10-27T19:11:28.822276Z","steps":["trace[867857881] 'process raft request' (duration: 170.733274ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:11:28.822619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.746851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T19:11:28.822696Z","caller":"traceutil/trace.go:171","msg":"trace[120412703] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:8885; }","duration":"147.083151ms","start":"2026-10-27T19:11:28.675591Z","end":"2026-10-27T19:11:28.822674Z","steps":["trace[120412703] 'agreement among raft nodes before linearized reading' (duration: 146.752641ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:11:33.010738Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002414.wal"} {"level":"warn","ts":"2026-10-27T19:12:47.573943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.233302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T19:12:47.574202Z","caller":"traceutil/trace.go:171","msg":"trace[629258888] linearizableReadLoop","detail":"{readStateIndex:9978; appliedIndex:9976; }","duration":"186.540906ms","start":"2026-10-27T19:12:47.387641Z","end":"2026-10-27T19:12:47.574182Z","steps":["trace[629258888] 'read index received' (duration: 977.946µs)","trace[629258888] 'applied index is now lower than readState.Index' (duration: 185.56177ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:12:47.574311Z","caller":"traceutil/trace.go:171","msg":"trace[1580264719] transaction","detail":"{read_only:false; response_revision:9642; number_of_response:1; }","duration":"277.254058ms","start":"2026-10-27T19:12:47.297024Z","end":"2026-10-27T19:12:47.574278Z","steps":["trace[1580264719] 'process raft request' (duration: 277.064353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:12:47.574382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.727481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-27T19:12:47.574504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.55147ms","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-27T19:12:47.574565Z","caller":"traceutil/trace.go:171","msg":"trace[1422045100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9642; }","duration":"185.639143ms","start":"2026-10-27T19:12:47.388911Z","end":"2026-10-27T19:12:47.57455Z","steps":["trace[1422045100] 'agreement among raft nodes before linearized reading' (duration: 185.414757ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:12:47.57458Z","caller":"traceutil/trace.go:171","msg":"trace[708396280] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:9642; }","duration":"186.964877ms","start":"2026-10-27T19:12:47.387568Z","end":"2026-10-27T19:12:47.574533Z","steps":["trace[708396280] 'agreement among raft nodes before linearized reading' (duration: 186.735421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:12:47.574504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.400937ms","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-10-27T19:12:47.574777Z","caller":"traceutil/trace.go:171","msg":"trace[172922801] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9642; }","duration":"101.718664ms","start":"2026-10-27T19:12:47.473042Z","end":"2026-10-27T19:12:47.57476Z","steps":["trace[172922801] 'agreement among raft nodes before linearized reading' (duration: 101.311844ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:12:47.575015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.378934ms","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-27T19:12:47.575085Z","caller":"traceutil/trace.go:171","msg":"trace[808814664] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9642; }","duration":"114.489747ms","start":"2026-10-27T19:12:47.460577Z","end":"2026-10-27T19:12:47.575066Z","steps":["trace[808814664] 'agreement among raft nodes before linearized reading' (duration: 113.788828ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:12:47.574239Z","caller":"traceutil/trace.go:171","msg":"trace[1144800741] transaction","detail":"{read_only:false; response_revision:9641; number_of_response:1; }","duration":"294.852648ms","start":"2026-10-27T19:12:47.279335Z","end":"2026-10-27T19:12:47.574187Z","steps":["trace[1144800741] 'process raft request' (duration: 109.265917ms)","trace[1144800741] 'compare' (duration: 184.989395ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:12:47.708979Z","caller":"traceutil/trace.go:171","msg":"trace[680306230] transaction","detail":"{read_only:false; response_revision:9643; number_of_response:1; }","duration":"130.218678ms","start":"2026-10-27T19:12:47.578731Z","end":"2026-10-27T19:12:47.708949Z","steps":["trace[680306230] 'process raft request' (duration: 102.205238ms)","trace[680306230] 'compare' (duration: 27.888587ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:12:47.871245Z","caller":"traceutil/trace.go:171","msg":"trace[981592159] transaction","detail":"{read_only:false; response_revision:9644; number_of_response:1; }","duration":"291.967511ms","start":"2026-10-27T19:12:47.579243Z","end":"2026-10-27T19:12:47.87121Z","steps":["trace[981592159] 'process raft request' (duration: 282.929379ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:12:47.87127Z","caller":"traceutil/trace.go:171","msg":"trace[1378484138] transaction","detail":"{read_only:false; response_revision:9645; number_of_response:1; }","duration":"291.791557ms","start":"2026-10-27T19:12:47.57945Z","end":"2026-10-27T19:12:47.871242Z","steps":["trace[1378484138] 'process raft request' (duration: 291.428767ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:12:51.162256Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"401ca643156fc5da","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T19:12:51.170804Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-27T19:12:51.170968Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-27T19:14:52.520907Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8096} {"level":"info","ts":"2026-10-27T19:14:52.601463Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8096,"took":"78.375535ms","hash":1822738979,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":28897280,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-27T19:14:52.601516Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1822738979,"revision":8096,"compact-revision":5914} {"level":"info","ts":"2026-10-27T19:16:15.13444Z","caller":"traceutil/trace.go:171","msg":"trace[1863383659] transaction","detail":"{read_only:false; response_revision:11399; number_of_response:1; }","duration":"113.089653ms","start":"2026-10-27T19:16:15.021317Z","end":"2026-10-27T19:16:15.134407Z","steps":["trace[1863383659] 'process raft request' (duration: 111.421199ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:16:29.842981Z","caller":"traceutil/trace.go:171","msg":"trace[1750128871] linearizableReadLoop","detail":"{readStateIndex:11870; appliedIndex:11869; }","duration":"114.569283ms","start":"2026-10-27T19:16:29.728387Z","end":"2026-10-27T19:16:29.842956Z","steps":["trace[1750128871] 'read index received' (duration: 43.577925ms)","trace[1750128871] 'applied index is now lower than readState.Index' (duration: 70.990108ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:16:29.843068Z","caller":"traceutil/trace.go:171","msg":"trace[361200992] transaction","detail":"{read_only:false; response_revision:11480; number_of_response:1; }","duration":"122.132395ms","start":"2026-10-27T19:16:29.720902Z","end":"2026-10-27T19:16:29.843035Z","steps":["trace[361200992] 'process raft request' (duration: 84.348305ms)","trace[361200992] 'compare' (duration: 37.597955ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:16:29.843158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.381948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T19:16:29.843195Z","caller":"traceutil/trace.go:171","msg":"trace[503602748] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11480; }","duration":"114.438199ms","start":"2026-10-27T19:16:29.728747Z","end":"2026-10-27T19:16:29.843185Z","steps":["trace[503602748] 'agreement among raft nodes before linearized reading' (duration: 114.354657ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:16:29.843224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.372721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-27T19:16:29.84332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.83895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T19:16:29.843332Z","caller":"traceutil/trace.go:171","msg":"trace[2038506137] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11480; }","duration":"110.490315ms","start":"2026-10-27T19:16:29.732823Z","end":"2026-10-27T19:16:29.843314Z","steps":["trace[2038506137] 'agreement among raft nodes before linearized reading' (duration: 110.367691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:16:29.843338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.109487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-10-27T19:16:29.843377Z","caller":"traceutil/trace.go:171","msg":"trace[1826729451] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11480; }","duration":"111.947323ms","start":"2026-10-27T19:16:29.731415Z","end":"2026-10-27T19:16:29.843363Z","steps":["trace[1826729451] 'agreement among raft nodes before linearized reading' (duration: 111.82021ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:16:29.843409Z","caller":"traceutil/trace.go:171","msg":"trace[435996624] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11480; }","duration":"106.22329ms","start":"2026-10-27T19:16:29.737164Z","end":"2026-10-27T19:16:29.843388Z","steps":["trace[435996624] 'agreement among raft nodes before linearized reading' (duration: 105.981983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:16:29.843464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.730487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T19:16:29.843501Z","caller":"traceutil/trace.go:171","msg":"trace[390037124] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11480; }","duration":"115.135658ms","start":"2026-10-27T19:16:29.728355Z","end":"2026-10-27T19:16:29.843491Z","steps":["trace[390037124] 'agreement among raft nodes before linearized reading' (duration: 114.704897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:16:30.173935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.936233ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T19:16:30.174064Z","caller":"traceutil/trace.go:171","msg":"trace[1622208036] transaction","detail":"{read_only:false; response_revision:11481; number_of_response:1; }","duration":"216.168729ms","start":"2026-10-27T19:16:29.957868Z","end":"2026-10-27T19:16:30.174037Z","steps":["trace[1622208036] 'process raft request' (duration: 92.027201ms)","trace[1622208036] 'compare' (duration: 123.718637ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:19:52.52767Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10536} {"level":"info","ts":"2026-10-27T19:19:52.620591Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10536,"took":"90.25058ms","hash":631423731,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":27459584,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-27T19:19:52.620658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":631423731,"revision":10536,"compact-revision":8096} {"level":"info","ts":"2026-10-27T19:22:41.44668Z","caller":"traceutil/trace.go:171","msg":"trace[1263220266] linearizableReadLoop","detail":"{readStateIndex:14462; appliedIndex:14461; }","duration":"153.839218ms","start":"2026-10-27T19:22:41.292816Z","end":"2026-10-27T19:22:41.446655Z","steps":["trace[1263220266] 'read index received' (duration: 153.563841ms)","trace[1263220266] 'applied index is now lower than readState.Index' (duration: 273.827µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:22:41.446814Z","caller":"traceutil/trace.go:171","msg":"trace[818564681] transaction","detail":"{read_only:false; response_revision:13984; number_of_response:1; }","duration":"182.773762ms","start":"2026-10-27T19:22:41.264Z","end":"2026-10-27T19:22:41.446774Z","steps":["trace[818564681] 'process raft request' (duration: 182.495015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:22:41.446896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.057154ms","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-27T19:22:41.446939Z","caller":"traceutil/trace.go:171","msg":"trace[1879042463] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13984; }","duration":"154.150786ms","start":"2026-10-27T19:22:41.292774Z","end":"2026-10-27T19:22:41.446925Z","steps":["trace[1879042463] 'agreement among raft nodes before linearized reading' (duration: 153.999082ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:22:59.946484Z","caller":"traceutil/trace.go:171","msg":"trace[81354474] transaction","detail":"{read_only:false; response_revision:14100; number_of_response:1; }","duration":"271.90434ms","start":"2026-10-27T19:22:59.674558Z","end":"2026-10-27T19:22:59.946463Z","steps":["trace[81354474] 'process raft request' (duration: 271.746775ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:23:00.276012Z","caller":"traceutil/trace.go:171","msg":"trace[1909855711] linearizableReadLoop","detail":"{readStateIndex:14584; appliedIndex:14582; }","duration":"526.551366ms","start":"2026-10-27T19:22:59.749427Z","end":"2026-10-27T19:23:00.275978Z","steps":["trace[1909855711] 'read index received' (duration: 196.915351ms)","trace[1909855711] 'applied index is now lower than readState.Index' (duration: 329.634605ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:23:00.276118Z","caller":"traceutil/trace.go:171","msg":"trace[1963229449] transaction","detail":"{read_only:false; response_revision:14101; number_of_response:1; }","duration":"575.205718ms","start":"2026-10-27T19:22:59.700865Z","end":"2026-10-27T19:23:00.27607Z","steps":["trace[1963229449] 'process raft request' (duration: 491.031105ms)","trace[1963229449] 'compare' (duration: 83.607728ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:23:00.276311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:22:59.700849Z","time spent":"575.371392ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T19:23:00.276304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.839063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T19:23:00.276414Z","caller":"traceutil/trace.go:171","msg":"trace[1134718776] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:14101; }","duration":"527.012707ms","start":"2026-10-27T19:22:59.74937Z","end":"2026-10-27T19:23:00.276382Z","steps":["trace[1134718776] 'agreement among raft nodes before linearized reading' (duration: 526.759901ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:23:00.276463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:22:59.74935Z","time spent":"527.10157ms","remote":"127.0.0.1:57010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"info","ts":"2026-10-27T19:23:00.522914Z","caller":"traceutil/trace.go:171","msg":"trace[2072602152] linearizableReadLoop","detail":"{readStateIndex:14585; appliedIndex:14584; }","duration":"246.779046ms","start":"2026-10-27T19:23:00.276098Z","end":"2026-10-27T19:23:00.522877Z","steps":["trace[2072602152] 'read index received' (duration: 228.547238ms)","trace[2072602152] 'applied index is now lower than readState.Index' (duration: 18.230358ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:23:00.523091Z","caller":"traceutil/trace.go:171","msg":"trace[2082908764] transaction","detail":"{read_only:false; response_revision:14102; number_of_response:1; }","duration":"516.566608ms","start":"2026-10-27T19:23:00.006508Z","end":"2026-10-27T19:23:00.523075Z","steps":["trace[2082908764] 'process raft request' (duration: 498.149016ms)","trace[2082908764] 'compare' (duration: 17.782895ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:23:00.523051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.598718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-27T19:23:00.523138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.554635ms","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-27T19:23:00.523181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:23:00.006489Z","time spent":"516.626549ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T19:23:00.523183Z","caller":"traceutil/trace.go:171","msg":"trace[979517526] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:14102; }","duration":"321.766323ms","start":"2026-10-27T19:23:00.201364Z","end":"2026-10-27T19:23:00.52313Z","steps":["trace[979517526] 'agreement among raft nodes before linearized reading' (duration: 321.523916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:23:00.523193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.545571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T19:23:00.523213Z","caller":"traceutil/trace.go:171","msg":"trace[1604196833] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14102; }","duration":"681.675688ms","start":"2026-10-27T19:22:59.84152Z","end":"2026-10-27T19:23:00.523196Z","steps":["trace[1604196833] 'agreement among raft nodes before linearized reading' (duration: 681.462703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:23:00.523229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.463026ms","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-27T19:23:00.523261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:22:59.841499Z","time spent":"681.75029ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-27T19:23:00.523256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:23:00.201336Z","time spent":"321.899487ms","remote":"127.0.0.1:60882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"info","ts":"2026-10-27T19:23:00.523294Z","caller":"traceutil/trace.go:171","msg":"trace[748065053] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14102; }","duration":"457.598279ms","start":"2026-10-27T19:23:00.065677Z","end":"2026-10-27T19:23:00.523275Z","steps":["trace[748065053] 'agreement among raft nodes before linearized reading' (duration: 457.393584ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:23:00.523292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.662732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mr926\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-10-27T19:23:00.523337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:23:00.065658Z","time spent":"457.667581ms","remote":"127.0.0.1:53498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12454,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-27T19:23:00.523343Z","caller":"traceutil/trace.go:171","msg":"trace[1283545649] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mr926; range_end:; response_count:1; response_revision:14102; }","duration":"559.761364ms","start":"2026-10-27T19:22:59.963568Z","end":"2026-10-27T19:23:00.52333Z","steps":["trace[1283545649] 'agreement among raft nodes before linearized reading' (duration: 559.526498ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:23:00.523245Z","caller":"traceutil/trace.go:171","msg":"trace[1679557697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14102; }","duration":"323.641143ms","start":"2026-10-27T19:23:00.19959Z","end":"2026-10-27T19:23:00.523231Z","steps":["trace[1679557697] 'agreement among raft nodes before linearized reading' (duration: 323.546671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:23:00.523378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:22:59.963531Z","time spent":"559.839056ms","remote":"127.0.0.1:53498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-mr926\" "} {"level":"warn","ts":"2026-10-27T19:23:00.523426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:23:00.199567Z","time spent":"323.836648ms","remote":"127.0.0.1:53330","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-27T19:23:00.642073Z","caller":"traceutil/trace.go:171","msg":"trace[1081336635] linearizableReadLoop","detail":"{readStateIndex:14586; appliedIndex:14585; }","duration":"114.325921ms","start":"2026-10-27T19:23:00.527713Z","end":"2026-10-27T19:23:00.642039Z","steps":["trace[1081336635] 'read index received' (duration: 101.23816ms)","trace[1081336635] 'applied index is now lower than readState.Index' (duration: 13.085891ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:23:00.642113Z","caller":"traceutil/trace.go:171","msg":"trace[1820599676] transaction","detail":"{read_only:false; response_revision:14103; number_of_response:1; }","duration":"114.994019ms","start":"2026-10-27T19:23:00.527083Z","end":"2026-10-27T19:23:00.642077Z","steps":["trace[1820599676] 'process raft request' (duration: 101.932498ms)","trace[1820599676] 'compare' (duration: 12.865825ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:23:00.642392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.658019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"info","ts":"2026-10-27T19:23:00.64244Z","caller":"traceutil/trace.go:171","msg":"trace[1499910482] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14103; }","duration":"114.745772ms","start":"2026-10-27T19:23:00.527682Z","end":"2026-10-27T19:23:00.642428Z","steps":["trace[1499910482] 'agreement among raft nodes before linearized reading' (duration: 114.546127ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:23:00.642981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.487375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:597458"} {"level":"info","ts":"2026-10-27T19:23:00.643038Z","caller":"traceutil/trace.go:171","msg":"trace[1513547365] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:14103; }","duration":"114.578278ms","start":"2026-10-27T19:23:00.528448Z","end":"2026-10-27T19:23:00.643026Z","steps":["trace[1513547365] 'agreement among raft nodes before linearized reading' (duration: 113.828998ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:24:37.690435Z","caller":"traceutil/trace.go:171","msg":"trace[1425616807] linearizableReadLoop","detail":"{readStateIndex:15807; appliedIndex:15806; }","duration":"103.874281ms","start":"2026-10-27T19:24:37.586536Z","end":"2026-10-27T19:24:37.69041Z","steps":["trace[1425616807] 'read index received' (duration: 103.712206ms)","trace[1425616807] 'applied index is now lower than readState.Index' (duration: 160.985µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:24:37.69058Z","caller":"traceutil/trace.go:171","msg":"trace[1522270445] transaction","detail":"{read_only:false; response_revision:15286; number_of_response:1; }","duration":"105.33167ms","start":"2026-10-27T19:24:37.585238Z","end":"2026-10-27T19:24:37.690569Z","steps":["trace[1522270445] 'process raft request' (duration: 105.020052ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:24:37.691115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.560359ms","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-10-27T19:24:37.691207Z","caller":"traceutil/trace.go:171","msg":"trace[1564565168] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15286; }","duration":"104.685962ms","start":"2026-10-27T19:24:37.586503Z","end":"2026-10-27T19:24:37.691189Z","steps":["trace[1564565168] 'agreement among raft nodes before linearized reading' (duration: 104.473797ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:24:38.692471Z","caller":"traceutil/trace.go:171","msg":"trace[1025113182] linearizableReadLoop","detail":"{readStateIndex:15811; appliedIndex:15810; }","duration":"132.326083ms","start":"2026-10-27T19:24:38.560121Z","end":"2026-10-27T19:24:38.692447Z","steps":["trace[1025113182] 'read index received' (duration: 132.21979ms)","trace[1025113182] 'applied index is now lower than readState.Index' (duration: 105.363µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:24:38.692763Z","caller":"traceutil/trace.go:171","msg":"trace[654501195] transaction","detail":"{read_only:false; response_revision:15290; number_of_response:1; }","duration":"156.372496ms","start":"2026-10-27T19:24:38.536378Z","end":"2026-10-27T19:24:38.69275Z","steps":["trace[654501195] 'process raft request' (duration: 155.918213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:24:38.693107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.97675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-27T19:24:38.693171Z","caller":"traceutil/trace.go:171","msg":"trace[1053735719] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15290; }","duration":"133.053333ms","start":"2026-10-27T19:24:38.560086Z","end":"2026-10-27T19:24:38.693139Z","steps":["trace[1053735719] 'agreement among raft nodes before linearized reading' (duration: 132.908969ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:24:38.693284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.854047ms","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-27T19:24:38.693367Z","caller":"traceutil/trace.go:171","msg":"trace[794376193] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15290; }","duration":"132.97402ms","start":"2026-10-27T19:24:38.56038Z","end":"2026-10-27T19:24:38.693354Z","steps":["trace[794376193] 'agreement among raft nodes before linearized reading' (duration: 132.824206ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:24:38.693563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.731477ms","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-27T19:24:38.693643Z","caller":"traceutil/trace.go:171","msg":"trace[1733470714] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15290; }","duration":"106.846971ms","start":"2026-10-27T19:24:38.586779Z","end":"2026-10-27T19:24:38.693626Z","steps":["trace[1733470714] 'agreement among raft nodes before linearized reading' (duration: 106.566923ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:24:52.537059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12793} {"level":"info","ts":"2026-10-27T19:24:52.629592Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12793,"took":"82.793247ms","hash":3381002182,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":30732288,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-27T19:24:52.629666Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3381002182,"revision":12793,"compact-revision":10536} {"level":"info","ts":"2026-10-27T19:29:52.544343Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15362} {"level":"info","ts":"2026-10-27T19:29:52.662188Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15362,"took":"114.733905ms","hash":2677400596,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":32690176,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-27T19:29:52.662276Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2677400596,"revision":15362,"compact-revision":12793} {"level":"info","ts":"2026-10-27T19:30:24.071458Z","caller":"traceutil/trace.go:171","msg":"trace[2040129333] transaction","detail":"{read_only:false; response_revision:17893; number_of_response:1; }","duration":"131.724218ms","start":"2026-10-27T19:30:23.939712Z","end":"2026-10-27T19:30:24.071436Z","steps":["trace[2040129333] 'process raft request' (duration: 131.560353ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:30:30.477613Z","caller":"traceutil/trace.go:171","msg":"trace[673129489] transaction","detail":"{read_only:false; response_revision:17932; number_of_response:1; }","duration":"155.230355ms","start":"2026-10-27T19:30:30.322355Z","end":"2026-10-27T19:30:30.477586Z","steps":["trace[673129489] 'process raft request' (duration: 155.085281ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:30:30.496882Z","caller":"traceutil/trace.go:171","msg":"trace[230988492] transaction","detail":"{read_only:false; response_revision:17933; number_of_response:1; }","duration":"149.01904ms","start":"2026-10-27T19:30:30.347842Z","end":"2026-10-27T19:30:30.496861Z","steps":["trace[230988492] 'process raft request' (duration: 148.938298ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:30:30.94326Z","caller":"traceutil/trace.go:171","msg":"trace[403974175] linearizableReadLoop","detail":"{readStateIndex:18543; appliedIndex:18542; }","duration":"102.793655ms","start":"2026-10-27T19:30:30.840431Z","end":"2026-10-27T19:30:30.943225Z","steps":["trace[403974175] 'read index received' (duration: 102.454166ms)","trace[403974175] 'applied index is now lower than readState.Index' (duration: 338.239µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:30:30.943293Z","caller":"traceutil/trace.go:171","msg":"trace[17884999] transaction","detail":"{read_only:false; response_revision:17934; number_of_response:1; }","duration":"102.869747ms","start":"2026-10-27T19:30:30.840384Z","end":"2026-10-27T19:30:30.943253Z","steps":["trace[17884999] 'process raft request' (duration: 102.494267ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:30:30.94364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.179125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-10-27T19:30:30.943705Z","caller":"traceutil/trace.go:171","msg":"trace[1449055529] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:17934; }","duration":"103.280998ms","start":"2026-10-27T19:30:30.840403Z","end":"2026-10-27T19:30:30.943684Z","steps":["trace[1449055529] 'agreement among raft nodes before linearized reading' (duration: 102.97446ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:30:51.747212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.038508ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-27T19:30:51.747478Z","caller":"traceutil/trace.go:171","msg":"trace[1041054514] transaction","detail":"{read_only:false; response_revision:18282; number_of_response:1; }","duration":"119.802829ms","start":"2026-10-27T19:30:51.627662Z","end":"2026-10-27T19:30:51.747465Z","steps":["trace[1041054514] 'process raft request' (duration: 119.645055ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:30:51.869445Z","caller":"traceutil/trace.go:171","msg":"trace[1356900217] transaction","detail":"{read_only:false; response_revision:18283; number_of_response:1; }","duration":"115.573796ms","start":"2026-10-27T19:30:51.753837Z","end":"2026-10-27T19:30:51.869411Z","steps":["trace[1356900217] 'process raft request' (duration: 107.166534ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:31:25.522342Z","caller":"traceutil/trace.go:171","msg":"trace[2132195527] transaction","detail":"{read_only:false; response_revision:18570; number_of_response:1; }","duration":"104.7206ms","start":"2026-10-27T19:31:25.41757Z","end":"2026-10-27T19:31:25.522291Z","steps":["trace[2132195527] 'process raft request' (duration: 104.433493ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:33:19.101182Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"401ca643156fc5da","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T19:33:19.110098Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-27T19:33:19.110199Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-27T19:34:52.552279Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17602} {"level":"info","ts":"2026-10-27T19:34:52.676838Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17602,"took":"121.217375ms","hash":1392695176,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":34336768,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-27T19:34:52.676914Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1392695176,"revision":17602,"compact-revision":15362} {"level":"info","ts":"2026-10-27T19:35:28.960154Z","caller":"traceutil/trace.go:171","msg":"trace[720077784] linearizableReadLoop","detail":"{readStateIndex:20964; appliedIndex:20963; }","duration":"145.158706ms","start":"2026-10-27T19:35:28.814977Z","end":"2026-10-27T19:35:28.960135Z","steps":["trace[720077784] 'read index received' (duration: 144.962201ms)","trace[720077784] 'applied index is now lower than readState.Index' (duration: 196.115µs)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:35:28.960307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.31319ms","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-27T19:35:28.960261Z","caller":"traceutil/trace.go:171","msg":"trace[875996783] transaction","detail":"{read_only:false; response_revision:20276; number_of_response:1; }","duration":"163.448115ms","start":"2026-10-27T19:35:28.796767Z","end":"2026-10-27T19:35:28.960215Z","steps":["trace[875996783] 'process raft request' (duration: 163.234059ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:35:28.960331Z","caller":"traceutil/trace.go:171","msg":"trace[1941868094] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20276; }","duration":"145.394022ms","start":"2026-10-27T19:35:28.81493Z","end":"2026-10-27T19:35:28.960324Z","steps":["trace[1941868094] 'agreement among raft nodes before linearized reading' (duration: 145.279059ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:35:29.157856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.217352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T19:35:29.15804Z","caller":"traceutil/trace.go:171","msg":"trace[1934552909] transaction","detail":"{read_only:false; response_revision:20277; number_of_response:1; }","duration":"193.303834ms","start":"2026-10-27T19:35:28.964716Z","end":"2026-10-27T19:35:29.15802Z","steps":["trace[1934552909] 'process raft request' (duration: 70.850086ms)","trace[1934552909] 'compare' (duration: 121.996636ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:35:29.495844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.793113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T19:35:29.495913Z","caller":"traceutil/trace.go:171","msg":"trace[1453194081] linearizableReadLoop","detail":"{readStateIndex:20966; appliedIndex:20965; }","duration":"296.385904ms","start":"2026-10-27T19:35:29.199515Z","end":"2026-10-27T19:35:29.495901Z","steps":["trace[1453194081] 'read index received' (duration: 141.454116ms)","trace[1453194081] 'applied index is now lower than readState.Index' (duration: 154.931138ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:35:29.495975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.470316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T19:35:29.495994Z","caller":"traceutil/trace.go:171","msg":"trace[1187078694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20278; }","duration":"296.522827ms","start":"2026-10-27T19:35:29.199462Z","end":"2026-10-27T19:35:29.495985Z","steps":["trace[1187078694] 'agreement among raft nodes before linearized reading' (duration: 296.492806ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:35:29.495984Z","caller":"traceutil/trace.go:171","msg":"trace[1326281965] transaction","detail":"{read_only:false; response_revision:20278; number_of_response:1; }","duration":"334.027922ms","start":"2026-10-27T19:35:29.161931Z","end":"2026-10-27T19:35:29.495959Z","steps":["trace[1326281965] 'process raft request' (duration: 179.066874ms)","trace[1326281965] 'compare' (duration: 154.719901ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:35:29.496144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.374374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-27T19:35:29.496142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:35:29.161916Z","time spent":"334.140495ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T19:35:29.496164Z","caller":"traceutil/trace.go:171","msg":"trace[1671557400] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:20278; }","duration":"206.442897ms","start":"2026-10-27T19:35:29.289714Z","end":"2026-10-27T19:35:29.496157Z","steps":["trace[1671557400] 'agreement among raft nodes before linearized reading' (duration: 206.410536ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:35:29.496311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.395943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-27T19:35:29.496327Z","caller":"traceutil/trace.go:171","msg":"trace[279747378] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20278; }","duration":"117.448124ms","start":"2026-10-27T19:35:29.378875Z","end":"2026-10-27T19:35:29.496323Z","steps":["trace[279747378] 'agreement among raft nodes before linearized reading' (duration: 117.377022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:35:29.896347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.198569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T19:35:29.896601Z","caller":"traceutil/trace.go:171","msg":"trace[2009577277] linearizableReadLoop","detail":"{readStateIndex:20968; appliedIndex:20966; }","duration":"213.362293ms","start":"2026-10-27T19:35:29.683222Z","end":"2026-10-27T19:35:29.896585Z","steps":["trace[2009577277] 'read index received' (duration: 31.001µs)","trace[2009577277] 'applied index is now lower than readState.Index' (duration: 213.317901ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:35:29.896699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.466685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-27T19:35:29.896734Z","caller":"traceutil/trace.go:171","msg":"trace[612347257] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:20280; }","duration":"213.538647ms","start":"2026-10-27T19:35:29.683182Z","end":"2026-10-27T19:35:29.896721Z","steps":["trace[612347257] 'agreement among raft nodes before linearized reading' (duration: 213.454715ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:35:29.896807Z","caller":"traceutil/trace.go:171","msg":"trace[264512054] transaction","detail":"{read_only:false; response_revision:20280; number_of_response:1; }","duration":"284.605849ms","start":"2026-10-27T19:35:29.612169Z","end":"2026-10-27T19:35:29.896775Z","steps":["trace[264512054] 'process raft request' (duration: 284.30422ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:35:29.896843Z","caller":"traceutil/trace.go:171","msg":"trace[428717727] transaction","detail":"{read_only:false; response_revision:20279; number_of_response:1; }","duration":"396.843273ms","start":"2026-10-27T19:35:29.499912Z","end":"2026-10-27T19:35:29.896755Z","steps":["trace[428717727] 'process raft request' (duration: 162.14665ms)","trace[428717727] 'compare' (duration: 233.718936ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:35:29.897738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:35:29.499897Z","time spent":"397.581702ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T19:35:30.17493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.022016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-27T19:35:30.175018Z","caller":"traceutil/trace.go:171","msg":"trace[1056313386] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20280; }","duration":"172.124188ms","start":"2026-10-27T19:35:30.002867Z","end":"2026-10-27T19:35:30.174992Z","steps":["trace[1056313386] 'count revisions from in-memory index tree' (duration: 171.8838ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:35:30.548194Z","caller":"traceutil/trace.go:171","msg":"trace[596157764] transaction","detail":"{read_only:false; response_revision:20281; number_of_response:1; }","duration":"134.461339ms","start":"2026-10-27T19:35:30.413717Z","end":"2026-10-27T19:35:30.548178Z","steps":["trace[596157764] 'process raft request' (duration: 134.357556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:35:30.872646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.652578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T19:35:30.872752Z","caller":"traceutil/trace.go:171","msg":"trace[2078558838] linearizableReadLoop","detail":"{readStateIndex:20970; appliedIndex:20969; }","duration":"294.990416ms","start":"2026-10-27T19:35:30.577745Z","end":"2026-10-27T19:35:30.872736Z","steps":["trace[2078558838] 'read index received' (duration: 164.169414ms)","trace[2078558838] 'applied index is now lower than readState.Index' (duration: 130.819382ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:35:30.872897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.14764ms","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-27T19:35:30.872933Z","caller":"traceutil/trace.go:171","msg":"trace[1542244367] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20282; }","duration":"295.199832ms","start":"2026-10-27T19:35:30.577721Z","end":"2026-10-27T19:35:30.87292Z","steps":["trace[1542244367] 'agreement among raft nodes before linearized reading' (duration: 295.057578ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:35:30.873136Z","caller":"traceutil/trace.go:171","msg":"trace[1509695711] transaction","detail":"{read_only:false; response_revision:20282; number_of_response:1; }","duration":"319.096552ms","start":"2026-10-27T19:35:30.554027Z","end":"2026-10-27T19:35:30.873124Z","steps":["trace[1509695711] 'process raft request' (duration: 187.869659ms)","trace[1509695711] 'compare' (duration: 130.472663ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:35:30.873198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:35:30.554012Z","time spent":"319.155223ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T19:35:30.875331Z","caller":"traceutil/trace.go:171","msg":"trace[1855681034] transaction","detail":"{read_only:false; response_revision:20283; number_of_response:1; }","duration":"275.362181ms","start":"2026-10-27T19:35:30.599951Z","end":"2026-10-27T19:35:30.875313Z","steps":["trace[1855681034] 'process raft request' (duration: 275.271918ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:35:31.131266Z","caller":"traceutil/trace.go:171","msg":"trace[1128775579] transaction","detail":"{read_only:false; response_revision:20285; number_of_response:1; }","duration":"162.424368ms","start":"2026-10-27T19:35:30.96881Z","end":"2026-10-27T19:35:31.131234Z","steps":["trace[1128775579] 'process raft request' (duration: 118.683138ms)","trace[1128775579] 'compare' (duration: 43.623177ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:39:20.478233Z","caller":"traceutil/trace.go:171","msg":"trace[1764542753] transaction","detail":"{read_only:false; response_revision:22668; number_of_response:1; }","duration":"247.68382ms","start":"2026-10-27T19:39:20.230525Z","end":"2026-10-27T19:39:20.478209Z","steps":["trace[1764542753] 'process raft request' (duration: 247.507285ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:39:20.479303Z","caller":"traceutil/trace.go:171","msg":"trace[1508375806] transaction","detail":"{read_only:false; response_revision:22669; number_of_response:1; }","duration":"227.192631ms","start":"2026-10-27T19:39:20.252096Z","end":"2026-10-27T19:39:20.479289Z","steps":["trace[1508375806] 'process raft request' (duration: 227.069208ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:39:52.568081Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20057} {"level":"info","ts":"2026-10-27T19:39:52.690081Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20057,"took":"118.087364ms","hash":2158903165,"current-db-size-bytes":56963072,"current-db-size":"57 MB","current-db-size-in-use-bytes":42860544,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-27T19:39:52.690146Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2158903165,"revision":20057,"compact-revision":17602} {"level":"warn","ts":"2026-10-27T19:42:32.051143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.438864ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-27T19:42:32.052018Z","caller":"traceutil/trace.go:171","msg":"trace[1391365293] transaction","detail":"{read_only:false; response_revision:24610; number_of_response:1; }","duration":"500.878106ms","start":"2026-10-27T19:42:31.551111Z","end":"2026-10-27T19:42:32.051989Z","steps":["trace[1391365293] 'process raft request' (duration: 500.182388ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:42:32.052007Z","caller":"traceutil/trace.go:171","msg":"trace[674369873] linearizableReadLoop","detail":"{readStateIndex:25423; appliedIndex:25421; }","duration":"479.033093ms","start":"2026-10-27T19:42:31.572944Z","end":"2026-10-27T19:42:32.051977Z","steps":["trace[674369873] 'read index received' (duration: 98.739766ms)","trace[674369873] 'applied index is now lower than readState.Index' (duration: 380.288676ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:42:32.052144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:42:31.551084Z","time spent":"500.987659ms","remote":"127.0.0.1:53582","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-27T19:42:32.079773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.695881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-27T19:42:32.07986Z","caller":"traceutil/trace.go:171","msg":"trace[1651376135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24610; }","duration":"509.834456ms","start":"2026-10-27T19:42:31.570002Z","end":"2026-10-27T19:42:32.079837Z","steps":["trace[1651376135] 'agreement among raft nodes before linearized reading' (duration: 482.116065ms)","trace[1651376135] 'range keys from in-memory index tree' (duration: 27.531065ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:42:32.079898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:42:31.569958Z","time spent":"509.932118ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-27T19:42:32.079927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.85727ms","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-27T19:42:32.080037Z","caller":"traceutil/trace.go:171","msg":"trace[855588276] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24610; }","duration":"521.017564ms","start":"2026-10-27T19:42:31.558988Z","end":"2026-10-27T19:42:32.080005Z","steps":["trace[855588276] 'agreement among raft nodes before linearized reading' (duration: 493.1491ms)","trace[855588276] 'range keys from in-memory index tree' (duration: 27.599597ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:42:32.080096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:42:31.558966Z","time spent":"521.117127ms","remote":"127.0.0.1:53582","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":"info","ts":"2026-10-27T19:42:32.080287Z","caller":"traceutil/trace.go:171","msg":"trace[503080532] transaction","detail":"{read_only:false; response_revision:24611; number_of_response:1; }","duration":"265.703116ms","start":"2026-10-27T19:42:31.814569Z","end":"2026-10-27T19:42:32.080272Z","steps":["trace[503080532] 'process raft request' (duration: 237.39596ms)","trace[503080532] 'compare' (duration: 27.467984ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:42:32.080707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.05407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T19:42:32.080751Z","caller":"traceutil/trace.go:171","msg":"trace[1482396780] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:24612; }","duration":"468.148002ms","start":"2026-10-27T19:42:31.61259Z","end":"2026-10-27T19:42:32.080738Z","steps":["trace[1482396780] 'agreement among raft nodes before linearized reading' (duration: 468.0609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:42:32.080784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:42:31.612566Z","time spent":"468.210463ms","remote":"127.0.0.1:39352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true "} {"level":"info","ts":"2026-10-27T19:42:32.080933Z","caller":"traceutil/trace.go:171","msg":"trace[67139703] transaction","detail":"{read_only:false; response_revision:24612; number_of_response:1; }","duration":"162.790307ms","start":"2026-10-27T19:42:31.918129Z","end":"2026-10-27T19:42:32.08092Z","steps":["trace[67139703] 'process raft request' (duration: 162.067828ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:42:32.081107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.043578ms","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-27T19:42:32.081139Z","caller":"traceutil/trace.go:171","msg":"trace[1831146146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24612; }","duration":"341.11766ms","start":"2026-10-27T19:42:31.740014Z","end":"2026-10-27T19:42:32.081131Z","steps":["trace[1831146146] 'agreement among raft nodes before linearized reading' (duration: 341.028937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:42:32.081167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:42:31.739991Z","time spent":"341.168341ms","remote":"127.0.0.1:53582","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-27T19:42:32.323198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.594601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T19:42:32.323355Z","caller":"traceutil/trace.go:171","msg":"trace[1393381271] transaction","detail":"{read_only:false; response_revision:24613; number_of_response:1; }","duration":"238.586151ms","start":"2026-10-27T19:42:32.084745Z","end":"2026-10-27T19:42:32.323331Z","steps":["trace[1393381271] 'process raft request' (duration: 132.747774ms)","trace[1393381271] 'compare' (duration: 105.453907ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:42:32.430238Z","caller":"traceutil/trace.go:171","msg":"trace[245785463] linearizableReadLoop","detail":"{readStateIndex:25428; appliedIndex:25425; }","duration":"306.125205ms","start":"2026-10-27T19:42:32.124089Z","end":"2026-10-27T19:42:32.430215Z","steps":["trace[245785463] 'read index received' (duration: 93.502537ms)","trace[245785463] 'applied index is now lower than readState.Index' (duration: 212.621858ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:42:32.430542Z","caller":"traceutil/trace.go:171","msg":"trace[1426204184] transaction","detail":"{read_only:false; response_revision:24614; number_of_response:1; }","duration":"344.457529ms","start":"2026-10-27T19:42:32.086072Z","end":"2026-10-27T19:42:32.43053Z","steps":["trace[1426204184] 'process raft request' (duration: 343.877184ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:42:32.430656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:42:32.086049Z","time spent":"344.530091ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T19:42:32.430863Z","caller":"traceutil/trace.go:171","msg":"trace[399132352] transaction","detail":"{read_only:false; response_revision:24615; number_of_response:1; }","duration":"344.670975ms","start":"2026-10-27T19:42:32.086174Z","end":"2026-10-27T19:42:32.430845Z","steps":["trace[399132352] 'process raft request' (duration: 343.916625ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:42:32.430944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:42:32.086164Z","time spent":"344.736877ms","remote":"127.0.0.1:53582","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-27T19:42:32.431096Z","caller":"traceutil/trace.go:171","msg":"trace[1493267836] transaction","detail":"{read_only:false; response_revision:24616; number_of_response:1; }","duration":"255.968655ms","start":"2026-10-27T19:42:32.175115Z","end":"2026-10-27T19:42:32.431083Z","steps":["trace[1493267836] 'process raft request' (duration: 255.02157ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:42:32.431225Z","caller":"traceutil/trace.go:171","msg":"trace[1407072545] transaction","detail":"{read_only:false; response_revision:24617; number_of_response:1; }","duration":"255.881593ms","start":"2026-10-27T19:42:32.175335Z","end":"2026-10-27T19:42:32.431217Z","steps":["trace[1407072545] 'process raft request' (duration: 254.839695ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:42:32.431379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.283226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T19:42:32.431424Z","caller":"traceutil/trace.go:171","msg":"trace[1151536069] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:24617; }","duration":"307.378698ms","start":"2026-10-27T19:42:32.12403Z","end":"2026-10-27T19:42:32.431409Z","steps":["trace[1151536069] 'agreement among raft nodes before linearized reading' (duration: 307.308896ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:42:32.431456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:42:32.124011Z","time spent":"307.43339ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-10-27T19:42:32.431678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.56561ms","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-27T19:42:32.431714Z","caller":"traceutil/trace.go:171","msg":"trace[622939099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24617; }","duration":"299.628152ms","start":"2026-10-27T19:42:32.132074Z","end":"2026-10-27T19:42:32.431702Z","steps":["trace[622939099] 'agreement among raft nodes before linearized reading' (duration: 299.516719ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:43:36.086424Z","caller":"traceutil/trace.go:171","msg":"trace[1364613772] transaction","detail":"{read_only:false; response_revision:25187; number_of_response:1; }","duration":"172.188158ms","start":"2026-10-27T19:43:35.914208Z","end":"2026-10-27T19:43:36.086396Z","steps":["trace[1364613772] 'process raft request' (duration: 172.014414ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:43:36.359349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.461213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.79\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-27T19:43:36.35941Z","caller":"traceutil/trace.go:171","msg":"trace[1824991173] range","detail":"{range_begin:/registry/masterleases/162.253.55.79; range_end:; response_count:1; response_revision:25187; }","duration":"182.567865ms","start":"2026-10-27T19:43:36.176826Z","end":"2026-10-27T19:43:36.359393Z","steps":["trace[1824991173] 'range keys from in-memory index tree' (duration: 182.266687ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:43:36.43489Z","caller":"traceutil/trace.go:171","msg":"trace[894512209] transaction","detail":"{read_only:false; response_revision:25188; number_of_response:1; }","duration":"124.870264ms","start":"2026-10-27T19:43:36.309991Z","end":"2026-10-27T19:43:36.434861Z","steps":["trace[894512209] 'process raft request' (duration: 124.699339ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:43:36.840635Z","caller":"traceutil/trace.go:171","msg":"trace[980048763] transaction","detail":"{read_only:false; response_revision:25189; number_of_response:1; }","duration":"521.078656ms","start":"2026-10-27T19:43:36.319526Z","end":"2026-10-27T19:43:36.840604Z","steps":["trace[980048763] 'process raft request' (duration: 520.830689ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:43:36.84081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:43:36.319498Z","time spent":"521.22698ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T19:43:36.841001Z","caller":"traceutil/trace.go:171","msg":"trace[2029858056] linearizableReadLoop","detail":"{readStateIndex:26017; appliedIndex:26014; }","duration":"119.640934ms","start":"2026-10-27T19:43:36.721327Z","end":"2026-10-27T19:43:36.840968Z","steps":["trace[2029858056] 'read index received' (duration: 119.044888ms)","trace[2029858056] 'applied index is now lower than readState.Index' (duration: 594.526µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:43:36.841061Z","caller":"traceutil/trace.go:171","msg":"trace[1668470918] transaction","detail":"{read_only:false; response_revision:25190; number_of_response:1; }","duration":"519.940695ms","start":"2026-10-27T19:43:36.321097Z","end":"2026-10-27T19:43:36.841037Z","steps":["trace[1668470918] 'process raft request' (duration: 519.446492ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:43:36.841111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:43:36.362389Z","time spent":"478.720015ms","remote":"127.0.0.1:53366","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-27T19:43:36.841206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:43:36.321082Z","time spent":"520.047917ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T19:43:36.841254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.915201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-27T19:43:36.841332Z","caller":"traceutil/trace.go:171","msg":"trace[383122741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25190; }","duration":"120.056655ms","start":"2026-10-27T19:43:36.721262Z","end":"2026-10-27T19:43:36.841318Z","steps":["trace[383122741] 'agreement among raft nodes before linearized reading' (duration: 119.85812ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:43:37.170286Z","caller":"traceutil/trace.go:171","msg":"trace[963603879] transaction","detail":"{read_only:false; response_revision:25191; number_of_response:1; }","duration":"446.263807ms","start":"2026-10-27T19:43:36.723988Z","end":"2026-10-27T19:43:37.170252Z","steps":["trace[963603879] 'process raft request' (duration: 446.01063ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:43:37.170349Z","caller":"traceutil/trace.go:171","msg":"trace[531843425] transaction","detail":"{read_only:false; response_revision:25192; number_of_response:1; }","duration":"438.0965ms","start":"2026-10-27T19:43:36.732184Z","end":"2026-10-27T19:43:37.17028Z","steps":["trace[531843425] 'process raft request' (duration: 437.970456ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:43:37.170459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:43:36.723965Z","time spent":"446.417131ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T19:43:37.170487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.331122ms","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-27T19:43:37.170525Z","caller":"traceutil/trace.go:171","msg":"trace[259803623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25194; }","duration":"421.402743ms","start":"2026-10-27T19:43:36.74911Z","end":"2026-10-27T19:43:37.170513Z","steps":["trace[259803623] 'agreement among raft nodes before linearized reading' (duration: 421.243499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:43:37.17052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:43:36.732159Z","time spent":"438.261354ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T19:43:37.170566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:43:36.749098Z","time spent":"421.460495ms","remote":"127.0.0.1:53582","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":"info","ts":"2026-10-27T19:43:37.170677Z","caller":"traceutil/trace.go:171","msg":"trace[851931470] transaction","detail":"{read_only:false; response_revision:25193; number_of_response:1; }","duration":"435.86356ms","start":"2026-10-27T19:43:36.734802Z","end":"2026-10-27T19:43:37.170666Z","steps":["trace[851931470] 'process raft request' (duration: 435.394268ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:43:37.170739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.621359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T19:43:37.170785Z","caller":"traceutil/trace.go:171","msg":"trace[938854096] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:25194; }","duration":"336.714572ms","start":"2026-10-27T19:43:36.834059Z","end":"2026-10-27T19:43:37.170773Z","steps":["trace[938854096] 'agreement among raft nodes before linearized reading' (duration: 336.605029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:43:37.170821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:43:36.834036Z","time spent":"336.775773ms","remote":"127.0.0.1:35802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true "} {"level":"warn","ts":"2026-10-27T19:43:37.170833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.95981ms","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-27T19:43:37.170863Z","caller":"traceutil/trace.go:171","msg":"trace[547458685] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25194; }","duration":"381.987701ms","start":"2026-10-27T19:43:36.788866Z","end":"2026-10-27T19:43:37.170853Z","steps":["trace[547458685] 'agreement among raft nodes before linearized reading' (duration: 381.902479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:43:37.170894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:43:36.788811Z","time spent":"382.077043ms","remote":"127.0.0.1:53582","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-27T19:43:37.171011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.748038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-27T19:43:37.171023Z","caller":"traceutil/trace.go:171","msg":"trace[1153622431] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"433.542298ms","start":"2026-10-27T19:43:36.737467Z","end":"2026-10-27T19:43:37.171009Z","steps":["trace[1153622431] 'process raft request' (duration: 432.762777ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:43:37.171043Z","caller":"traceutil/trace.go:171","msg":"trace[1370342886] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25194; }","duration":"389.824851ms","start":"2026-10-27T19:43:36.78121Z","end":"2026-10-27T19:43:37.171035Z","steps":["trace[1370342886] 'agreement among raft nodes before linearized reading' (duration: 389.745139ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:43:37.171066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:43:36.781189Z","time spent":"389.871421ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-27T19:43:37.171084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:43:36.737454Z","time spent":"433.596359ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T19:43:37.170283Z","caller":"traceutil/trace.go:171","msg":"trace[125715748] linearizableReadLoop","detail":"{readStateIndex:26021; appliedIndex:26017; }","duration":"329.179421ms","start":"2026-10-27T19:43:36.841084Z","end":"2026-10-27T19:43:37.170263Z","steps":["trace[125715748] 'read index received' (duration: 328.893804ms)","trace[125715748] 'applied index is now lower than readState.Index' (duration: 285.017µs)"],"step_count":2} {"level":"warn","ts":"2026-10-27T19:43:37.170747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:43:36.73478Z","time spent":"435.926142ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T19:43:37.178165Z","caller":"traceutil/trace.go:171","msg":"trace[2006360128] transaction","detail":"{read_only:false; response_revision:25196; number_of_response:1; }","duration":"332.644964ms","start":"2026-10-27T19:43:36.845499Z","end":"2026-10-27T19:43:37.178144Z","steps":["trace[2006360128] 'process raft request' (duration: 332.596663ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:43:37.178239Z","caller":"traceutil/trace.go:171","msg":"trace[658414758] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"336.086905ms","start":"2026-10-27T19:43:36.842124Z","end":"2026-10-27T19:43:37.178211Z","steps":["trace[658414758] 'process raft request' (duration: 335.868209ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:43:37.178324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:43:36.845475Z","time spent":"332.755097ms","remote":"127.0.0.1:53582","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-27T19:43:37.178389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T19:43:36.842106Z","time spent":"336.216049ms","remote":"127.0.0.1:53366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T19:44:08.548928Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006721.wal"} {"level":"info","ts":"2026-10-27T19:44:52.581385Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22976} {"level":"info","ts":"2026-10-27T19:44:52.728243Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22976,"took":"142.970569ms","hash":1927024878,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":35999744,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-27T19:44:52.728312Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1927024878,"revision":22976,"compact-revision":20057} {"level":"info","ts":"2026-10-27T19:45:24.76695Z","caller":"traceutil/trace.go:171","msg":"trace[407043262] linearizableReadLoop","detail":"{readStateIndex:27094; appliedIndex:27093; }","duration":"102.387324ms","start":"2026-10-27T19:45:24.664542Z","end":"2026-10-27T19:45:24.76693Z","steps":["trace[407043262] 'read index received' (duration: 102.121757ms)","trace[407043262] 'applied index is now lower than readState.Index' (duration: 264.377µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T19:45:24.767023Z","caller":"traceutil/trace.go:171","msg":"trace[1572079983] transaction","detail":"{read_only:false; response_revision:26242; number_of_response:1; }","duration":"107.748577ms","start":"2026-10-27T19:45:24.659236Z","end":"2026-10-27T19:45:24.766984Z","steps":["trace[1572079983] 'process raft request' (duration: 107.532462ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T19:45:24.767282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.719952ms","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-27T19:45:24.767381Z","caller":"traceutil/trace.go:171","msg":"trace[1093907136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26242; }","duration":"102.855746ms","start":"2026-10-27T19:45:24.664489Z","end":"2026-10-27T19:45:24.767345Z","steps":["trace[1093907136] 'agreement among raft nodes before linearized reading' (duration: 102.565318ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T19:49:52.588583Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25918} {"level":"info","ts":"2026-10-27T19:49:52.760579Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25918,"took":"167.33983ms","hash":2460960319,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":34426880,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-27T19:49:52.760653Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2460960319,"revision":25918,"compact-revision":22976} {"level":"info","ts":"2026-10-27T19:50:51.582449Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"401ca643156fc5da","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T19:50:51.587997Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-27T19:50:51.588079Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-27T19:54:52.610864Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28607} {"level":"info","ts":"2026-10-27T19:54:52.776249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28607,"took":"161.139255ms","hash":3329145450,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":31055872,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-27T19:54:52.776351Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3329145450,"revision":28607,"compact-revision":25918} {"level":"info","ts":"2026-10-27T19:59:52.620098Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30828} {"level":"info","ts":"2026-10-27T19:59:52.741522Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30828,"took":"117.268712ms","hash":680564285,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":31076352,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-27T19:59:52.741591Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":680564285,"revision":30828,"compact-revision":28607} {"level":"info","ts":"2026-10-27T20:04:52.633162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33095} {"level":"info","ts":"2026-10-27T20:04:52.8133Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33095,"took":"175.834906ms","hash":2633125701,"current-db-size-bytes":58847232,"current-db-size":"59 MB","current-db-size-in-use-bytes":32415744,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-27T20:04:52.81337Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2633125701,"revision":33095,"compact-revision":30828} {"level":"info","ts":"2026-10-27T20:09:52.643862Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35456} {"level":"info","ts":"2026-10-27T20:09:52.800417Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35456,"took":"152.554105ms","hash":2045601693,"current-db-size-bytes":59314176,"current-db-size":"59 MB","current-db-size-in-use-bytes":31793152,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-27T20:09:52.80049Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2045601693,"revision":35456,"compact-revision":33095} {"level":"info","ts":"2026-10-27T20:12:07.72679Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"401ca643156fc5da","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T20:12:07.732944Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-27T20:12:07.733074Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-27T20:14:52.651235Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37714} {"level":"info","ts":"2026-10-27T20:14:52.784479Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37714,"took":"129.035121ms","hash":14122306,"current-db-size-bytes":59314176,"current-db-size":"59 MB","current-db-size-in-use-bytes":31739904,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-27T20:14:52.784549Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":14122306,"revision":37714,"compact-revision":35456}