{"level":"warn","ts":"2026-09-19T01:46:56.104048Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T01:46:56.104186Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.250: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.250:2380","--initial-cluster=instance=https://162.253.55.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.250: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-09-19T01:46:56.10429Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T01:46:56.104308Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.250:2380"]} {"level":"info","ts":"2026-09-19T01:46:56.10434Z","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-09-19T01:46:56.105532Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.250:2379"]} {"level":"info","ts":"2026-09-19T01:46:56.10571Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.250:2380"],"listen-peer-urls":["https://162.253.55.250:2380"],"advertise-client-urls":["https://162.253.55.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.250: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-09-19T01:46:56.115862Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.772364ms"} {"level":"info","ts":"2026-09-19T01:46:56.126122Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1e4a0bccff66c64d","cluster-id":"8ba64f6e83da771"} {"level":"info","ts":"2026-09-19T01:46:56.126651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T01:46:56.126688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became follower at term 0"} {"level":"info","ts":"2026-09-19T01:46:56.126702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1e4a0bccff66c64d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T01:46:56.126712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became follower at term 1"} {"level":"info","ts":"2026-09-19T01:46:56.126772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d switched to configuration voters=(2182569944500586061)"} {"level":"warn","ts":"2026-09-19T01:46:56.136596Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T01:46:56.138409Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T01:46:56.14085Z","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-09-19T01:46:56.144899Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1e4a0bccff66c64d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T01:46:56.146343Z","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-09-19T01:46:56.146504Z","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-09-19T01:46:56.146522Z","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-09-19T01:46:56.145916Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1e4a0bccff66c64d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T01:46:56.154162Z","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-09-19T01:46:56.15491Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1e4a0bccff66c64d","initial-advertise-peer-urls":["https://162.253.55.250:2380"],"listen-peer-urls":["https://162.253.55.250:2380"],"advertise-client-urls":["https://162.253.55.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T01:46:56.155029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d switched to configuration voters=(2182569944500586061)"} {"level":"info","ts":"2026-09-19T01:46:56.155004Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.250:2380"} {"level":"info","ts":"2026-09-19T01:46:56.15516Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.250:2380"} {"level":"info","ts":"2026-09-19T01:46:56.154977Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T01:46:56.155242Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ba64f6e83da771","local-member-id":"1e4a0bccff66c64d","added-peer-id":"1e4a0bccff66c64d","added-peer-peer-urls":["https://162.253.55.250:2380"]} {"level":"info","ts":"2026-09-19T01:46:56.227628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T01:46:56.227696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T01:46:56.227739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d received MsgPreVoteResp from 1e4a0bccff66c64d at term 1"} {"level":"info","ts":"2026-09-19T01:46:56.227756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became candidate at term 2"} {"level":"info","ts":"2026-09-19T01:46:56.227764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d received MsgVoteResp from 1e4a0bccff66c64d at term 2"} {"level":"info","ts":"2026-09-19T01:46:56.227872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1e4a0bccff66c64d became leader at term 2"} {"level":"info","ts":"2026-09-19T01:46:56.227891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1e4a0bccff66c64d elected leader 1e4a0bccff66c64d at term 2"} {"level":"info","ts":"2026-09-19T01:46:56.229264Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:46:56.229455Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T01:46:56.229451Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1e4a0bccff66c64d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.250:2379]}","request-path":"/0/members/1e4a0bccff66c64d/attributes","cluster-id":"8ba64f6e83da771","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T01:46:56.229541Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T01:46:56.229894Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T01:46:56.229955Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T01:46:56.232514Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.250:2379"} {"level":"info","ts":"2026-09-19T01:46:56.230566Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ba64f6e83da771","local-member-id":"1e4a0bccff66c64d","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:46:56.232776Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:46:56.232818Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:46:56.234155Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-19T01:46:59.779865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.866763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-19T01:46:59.780145Z","caller":"traceutil/trace.go:171","msg":"trace[391210731] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"549.174767ms","start":"2026-09-19T01:46:59.230944Z","end":"2026-09-19T01:46:59.780119Z","steps":["trace[391210731] 'process raft request' (duration: 266.158702ms)","trace[391210731] 'compare' (duration: 281.647115ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:46:59.78027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:46:59.230922Z","time spent":"549.315251ms","remote":"127.0.0.1:42450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":600,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T01:46:59.960701Z","caller":"traceutil/trace.go:171","msg":"trace[42729873] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"174.284961ms","start":"2026-09-19T01:46:59.786353Z","end":"2026-09-19T01:46:59.960638Z","steps":["trace[42729873] 'process raft request' (duration: 101.997445ms)","trace[42729873] 'compare' (duration: 72.117959ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:47:49.071772Z","caller":"traceutil/trace.go:171","msg":"trace[97216056] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"340.446493ms","start":"2026-09-19T01:47:48.73129Z","end":"2026-09-19T01:47:49.071737Z","steps":["trace[97216056] 'process raft request' (duration: 338.727003ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:47:49.074358Z","caller":"traceutil/trace.go:171","msg":"trace[1663387064] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"272.888808ms","start":"2026-09-19T01:47:48.798808Z","end":"2026-09-19T01:47:49.071697Z","steps":["trace[1663387064] 'read index received' (duration: 270.519423ms)","trace[1663387064] 'applied index is now lower than readState.Index' (duration: 2.368385ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:47:49.07441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.582361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-19T01:47:49.074488Z","caller":"traceutil/trace.go:171","msg":"trace[1928874545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"275.653402ms","start":"2026-09-19T01:47:48.798802Z","end":"2026-09-19T01:47:49.074455Z","steps":["trace[1928874545] 'agreement among raft nodes before linearized reading' (duration: 273.123023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:47:49.07453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:47:48.731212Z","time spent":"340.687299ms","remote":"127.0.0.1:42188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:47:49.078626Z","caller":"traceutil/trace.go:171","msg":"trace[460240275] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"233.714574ms","start":"2026-09-19T01:47:48.844866Z","end":"2026-09-19T01:47:49.078581Z","steps":["trace[460240275] 'process raft request' (duration: 233.08983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:47:49.079745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.47771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x8qkk\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-19T01:47:49.07987Z","caller":"traceutil/trace.go:171","msg":"trace[1257888190] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x8qkk; range_end:; response_count:1; response_revision:652; }","duration":"270.603864ms","start":"2026-09-19T01:47:48.80921Z","end":"2026-09-19T01:47:49.079814Z","steps":["trace[1257888190] 'agreement among raft nodes before linearized reading' (duration: 268.963686ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:47:49.508633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.528104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:47:49.508772Z","caller":"traceutil/trace.go:171","msg":"trace[552954481] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"402.420136ms","start":"2026-09-19T01:47:49.106333Z","end":"2026-09-19T01:47:49.508753Z","steps":["trace[552954481] 'read index received' (duration: 298.690617ms)","trace[552954481] 'applied index is now lower than readState.Index' (duration: 103.727699ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:47:49.508998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.663051ms","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-09-19T01:47:49.509033Z","caller":"traceutil/trace.go:171","msg":"trace[1589102518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"402.698752ms","start":"2026-09-19T01:47:49.106324Z","end":"2026-09-19T01:47:49.509023Z","steps":["trace[1589102518] 'agreement among raft nodes before linearized reading' (duration: 402.493047ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:47:49.509066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:47:49.106255Z","time spent":"402.802904ms","remote":"127.0.0.1:42390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-19T01:47:49.509556Z","caller":"traceutil/trace.go:171","msg":"trace[661891653] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"410.341318ms","start":"2026-09-19T01:47:49.098527Z","end":"2026-09-19T01:47:49.508869Z","steps":["trace[661891653] 'process raft request' (duration: 306.486937ms)","trace[661891653] 'compare' (duration: 103.382491ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:47:49.51016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:47:49.0985Z","time spent":"411.557176ms","remote":"127.0.0.1:42292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:47:49.688031Z","caller":"traceutil/trace.go:171","msg":"trace[1743723697] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"532.379271ms","start":"2026-09-19T01:47:49.155623Z","end":"2026-09-19T01:47:49.688003Z","steps":["trace[1743723697] 'process raft request' (duration: 532.234708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:47:49.688182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:47:49.155597Z","time spent":"532.516093ms","remote":"127.0.0.1:42614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T01:47:49.691747Z","caller":"traceutil/trace.go:171","msg":"trace[47324969] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:685; }","duration":"130.520278ms","start":"2026-09-19T01:47:49.561199Z","end":"2026-09-19T01:47:49.691719Z","steps":["trace[47324969] 'read index received' (duration: 127.333445ms)","trace[47324969] 'applied index is now lower than readState.Index' (duration: 3.185493ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:47:49.691851Z","caller":"traceutil/trace.go:171","msg":"trace[1944143099] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"176.071761ms","start":"2026-09-19T01:47:49.515718Z","end":"2026-09-19T01:47:49.69179Z","steps":["trace[1944143099] 'process raft request' (duration: 175.890457ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:47:49.691997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.779734ms","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-09-19T01:47:49.692045Z","caller":"traceutil/trace.go:171","msg":"trace[414044154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:657; }","duration":"130.915017ms","start":"2026-09-19T01:47:49.561119Z","end":"2026-09-19T01:47:49.692035Z","steps":["trace[414044154] 'agreement among raft nodes before linearized reading' (duration: 130.687252ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:47:51.114503Z","caller":"traceutil/trace.go:171","msg":"trace[817259829] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"102.562795ms","start":"2026-09-19T01:47:51.011907Z","end":"2026-09-19T01:47:51.11447Z","steps":["trace[817259829] 'read index received' (duration: 99.379293ms)","trace[817259829] 'applied index is now lower than readState.Index' (duration: 3.181572ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:47:51.114695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.750859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:47:51.11474Z","caller":"traceutil/trace.go:171","msg":"trace[333095713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"102.840321ms","start":"2026-09-19T01:47:51.011887Z","end":"2026-09-19T01:47:51.114727Z","steps":["trace[333095713] 'agreement among raft nodes before linearized reading' (duration: 102.730899ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:48:40.527095Z","caller":"traceutil/trace.go:171","msg":"trace[1529187249] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"159.251504ms","start":"2026-09-19T01:48:40.367811Z","end":"2026-09-19T01:48:40.527063Z","steps":["trace[1529187249] 'process raft request' (duration: 159.073071ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:49:28.755481Z","caller":"traceutil/trace.go:171","msg":"trace[282343239] linearizableReadLoop","detail":"{readStateIndex:1986; appliedIndex:1985; }","duration":"130.570944ms","start":"2026-09-19T01:49:28.62488Z","end":"2026-09-19T01:49:28.755451Z","steps":["trace[282343239] 'read index received' (duration: 130.360541ms)","trace[282343239] 'applied index is now lower than readState.Index' (duration: 209.453µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:49:28.755564Z","caller":"traceutil/trace.go:171","msg":"trace[1755519467] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"308.744812ms","start":"2026-09-19T01:49:28.446776Z","end":"2026-09-19T01:49:28.755521Z","steps":["trace[1755519467] 'process raft request' (duration: 308.515889ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:49:28.755794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.765486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dc5c46a3-6630-490a-8fe2-a4fc1edcd41f\" ","response":"range_response_count:1 size:3747"} {"level":"warn","ts":"2026-09-19T01:49:28.755799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:49:28.446749Z","time spent":"308.908425ms","remote":"127.0.0.1:42292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:49:28.755846Z","caller":"traceutil/trace.go:171","msg":"trace[945315485] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dc5c46a3-6630-490a-8fe2-a4fc1edcd41f; range_end:; response_count:1; response_revision:1903; }","duration":"131.011291ms","start":"2026-09-19T01:49:28.62482Z","end":"2026-09-19T01:49:28.755831Z","steps":["trace[945315485] 'agreement among raft nodes before linearized reading' (duration: 130.769567ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:49:37.704468Z","caller":"traceutil/trace.go:171","msg":"trace[1223841905] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"213.807898ms","start":"2026-09-19T01:49:37.490623Z","end":"2026-09-19T01:49:37.704431Z","steps":["trace[1223841905] 'process raft request' (duration: 125.703586ms)","trace[1223841905] 'compare' (duration: 87.726267ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:49:37.704571Z","caller":"traceutil/trace.go:171","msg":"trace[1174306098] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"132.798252ms","start":"2026-09-19T01:49:37.571741Z","end":"2026-09-19T01:49:37.70454Z","steps":["trace[1174306098] 'process raft request' (duration: 132.65989ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:49:37.704683Z","caller":"traceutil/trace.go:171","msg":"trace[272839186] linearizableReadLoop","detail":"{readStateIndex:2073; appliedIndex:2072; }","duration":"212.646802ms","start":"2026-09-19T01:49:37.492023Z","end":"2026-09-19T01:49:37.70467Z","steps":["trace[272839186] 'read index received' (duration: 124.320027ms)","trace[272839186] 'applied index is now lower than readState.Index' (duration: 88.325355ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:49:37.704959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.880624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-09-19T01:49:37.704995Z","caller":"traceutil/trace.go:171","msg":"trace[253698160] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1989; }","duration":"213.005277ms","start":"2026-09-19T01:49:37.491976Z","end":"2026-09-19T01:49:37.704981Z","steps":["trace[253698160] 'agreement among raft nodes before linearized reading' (duration: 212.786564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:49:37.70522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.591758ms","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-09-19T01:49:37.705321Z","caller":"traceutil/trace.go:171","msg":"trace[1114523711] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1989; }","duration":"126.701889ms","start":"2026-09-19T01:49:37.578587Z","end":"2026-09-19T01:49:37.705289Z","steps":["trace[1114523711] 'agreement among raft nodes before linearized reading' (duration: 126.394025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:50:02.90177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.167477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T01:50:02.901893Z","caller":"traceutil/trace.go:171","msg":"trace[656380585] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2147; }","duration":"100.36369ms","start":"2026-09-19T01:50:02.801504Z","end":"2026-09-19T01:50:02.901868Z","steps":["trace[656380585] 'count revisions from in-memory index tree' (duration: 99.957795ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:50:02.902066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.710478ms","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-09-19T01:50:02.902193Z","caller":"traceutil/trace.go:171","msg":"trace[387878680] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2147; }","duration":"125.87904ms","start":"2026-09-19T01:50:02.776294Z","end":"2026-09-19T01:50:02.902173Z","steps":["trace[387878680] 'count revisions from in-memory index tree' (duration: 125.596126ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:50:04.11174Z","caller":"traceutil/trace.go:171","msg":"trace[819064409] linearizableReadLoop","detail":"{readStateIndex:2241; appliedIndex:2240; }","duration":"222.675917ms","start":"2026-09-19T01:50:03.889034Z","end":"2026-09-19T01:50:04.11171Z","steps":["trace[819064409] 'read index received' (duration: 7.208542ms)","trace[819064409] 'applied index is now lower than readState.Index' (duration: 215.465245ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:50:04.111833Z","caller":"traceutil/trace.go:171","msg":"trace[482419466] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"235.238241ms","start":"2026-09-19T01:50:03.876528Z","end":"2026-09-19T01:50:04.111766Z","steps":["trace[482419466] 'process raft request' (duration: 232.447934ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:50:04.11208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.035603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-19T01:50:04.112214Z","caller":"traceutil/trace.go:171","msg":"trace[1751939493] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2150; }","duration":"223.192965ms","start":"2026-09-19T01:50:03.888966Z","end":"2026-09-19T01:50:04.112159Z","steps":["trace[1751939493] 'agreement among raft nodes before linearized reading' (duration: 222.932641ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:50:04.113197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.484316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-19T01:50:04.113256Z","caller":"traceutil/trace.go:171","msg":"trace[80524715] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2150; }","duration":"205.563517ms","start":"2026-09-19T01:50:03.907678Z","end":"2026-09-19T01:50:04.113242Z","steps":["trace[80524715] 'agreement among raft nodes before linearized reading' (duration: 205.321853ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:50:04.113638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.668501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-19T01:50:04.114086Z","caller":"traceutil/trace.go:171","msg":"trace[1621629144] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2150; }","duration":"199.847112ms","start":"2026-09-19T01:50:03.913906Z","end":"2026-09-19T01:50:04.113753Z","steps":["trace[1621629144] 'agreement among raft nodes before linearized reading' (duration: 199.602849ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:51:46.048834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.95435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T01:51:46.049325Z","caller":"traceutil/trace.go:171","msg":"trace[645387513] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2799; }","duration":"197.482718ms","start":"2026-09-19T01:51:45.851808Z","end":"2026-09-19T01:51:46.049291Z","steps":["trace[645387513] 'count revisions from in-memory index tree' (duration: 196.854796ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:51:51.458342Z","caller":"traceutil/trace.go:171","msg":"trace[1937700110] transaction","detail":"{read_only:false; response_revision:2830; number_of_response:1; }","duration":"139.958372ms","start":"2026-09-19T01:51:51.318354Z","end":"2026-09-19T01:51:51.458312Z","steps":["trace[1937700110] 'process raft request' (duration: 139.757624ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:51:51.469152Z","caller":"traceutil/trace.go:171","msg":"trace[962869100] linearizableReadLoop","detail":"{readStateIndex:2946; appliedIndex:2944; }","duration":"146.300557ms","start":"2026-09-19T01:51:51.322818Z","end":"2026-09-19T01:51:51.469119Z","steps":["trace[962869100] 'read index received' (duration: 135.365605ms)","trace[962869100] 'applied index is now lower than readState.Index' (duration: 10.933292ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:51:51.469442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.592266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18d695dedb439329\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-09-19T01:51:51.469498Z","caller":"traceutil/trace.go:171","msg":"trace[1014164608] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18d695dedb439329; range_end:; response_count:1; response_revision:2831; }","duration":"146.69838ms","start":"2026-09-19T01:51:51.322787Z","end":"2026-09-19T01:51:51.469485Z","steps":["trace[1014164608] 'agreement among raft nodes before linearized reading' (duration: 146.456842ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:51:51.469712Z","caller":"traceutil/trace.go:171","msg":"trace[1442941462] transaction","detail":"{read_only:false; response_revision:2831; number_of_response:1; }","duration":"150.656324ms","start":"2026-09-19T01:51:51.318652Z","end":"2026-09-19T01:51:51.469309Z","steps":["trace[1442941462] 'process raft request' (duration: 150.303883ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:33.932668Z","caller":"traceutil/trace.go:171","msg":"trace[593452396] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"127.243667ms","start":"2026-09-19T01:56:33.805392Z","end":"2026-09-19T01:56:33.932635Z","steps":["trace[593452396] 'process raft request' (duration: 126.908411ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:33.989032Z","caller":"traceutil/trace.go:171","msg":"trace[1411328005] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"148.460581ms","start":"2026-09-19T01:56:33.840543Z","end":"2026-09-19T01:56:33.989004Z","steps":["trace[1411328005] 'process raft request' (duration: 148.339779ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:33.98906Z","caller":"traceutil/trace.go:171","msg":"trace[248217301] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"178.871934ms","start":"2026-09-19T01:56:33.810159Z","end":"2026-09-19T01:56:33.989031Z","steps":["trace[248217301] 'process raft request' (duration: 178.529018ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:37.18977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.696479ms","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-09-19T01:56:37.189855Z","caller":"traceutil/trace.go:171","msg":"trace[1546474751] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4970; }","duration":"105.834481ms","start":"2026-09-19T01:56:37.084004Z","end":"2026-09-19T01:56:37.189838Z","steps":["trace[1546474751] 'range keys from in-memory index tree' (duration: 105.627448ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:41.255228Z","caller":"traceutil/trace.go:171","msg":"trace[1216615407] transaction","detail":"{read_only:false; response_revision:5054; number_of_response:1; }","duration":"159.603733ms","start":"2026-09-19T01:56:41.095561Z","end":"2026-09-19T01:56:41.255165Z","steps":["trace[1216615407] 'process raft request' (duration: 101.296378ms)","trace[1216615407] 'compare' (duration: 58.05293ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:56:41.255312Z","caller":"traceutil/trace.go:171","msg":"trace[1924378926] linearizableReadLoop","detail":"{readStateIndex:5255; appliedIndex:5253; }","duration":"158.278141ms","start":"2026-09-19T01:56:41.096978Z","end":"2026-09-19T01:56:41.255257Z","steps":["trace[1924378926] 'read index received' (duration: 38.127381ms)","trace[1924378926] 'applied index is now lower than readState.Index' (duration: 120.14777ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:56:41.255629Z","caller":"traceutil/trace.go:171","msg":"trace[833134485] transaction","detail":"{read_only:false; response_revision:5055; number_of_response:1; }","duration":"119.186303ms","start":"2026-09-19T01:56:41.136422Z","end":"2026-09-19T01:56:41.255608Z","steps":["trace[833134485] 'process raft request' (duration: 119.070041ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:41.255664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.657287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11455"} {"level":"info","ts":"2026-09-19T01:56:41.255778Z","caller":"traceutil/trace.go:171","msg":"trace[853444545] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5054; }","duration":"158.8265ms","start":"2026-09-19T01:56:41.09692Z","end":"2026-09-19T01:56:41.255747Z","steps":["trace[853444545] 'agreement among raft nodes before linearized reading' (duration: 158.434173ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:41.608482Z","caller":"traceutil/trace.go:171","msg":"trace[991522766] linearizableReadLoop","detail":"{readStateIndex:5263; appliedIndex:5262; }","duration":"150.428796ms","start":"2026-09-19T01:56:41.458022Z","end":"2026-09-19T01:56:41.60845Z","steps":["trace[991522766] 'read index received' (duration: 150.191692ms)","trace[991522766] 'applied index is now lower than readState.Index' (duration: 235.794µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:56:41.608641Z","caller":"traceutil/trace.go:171","msg":"trace[943072282] transaction","detail":"{read_only:false; response_revision:5061; number_of_response:1; }","duration":"173.384458ms","start":"2026-09-19T01:56:41.435231Z","end":"2026-09-19T01:56:41.608615Z","steps":["trace[943072282] 'process raft request' (duration: 173.031853ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:41.608857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.770792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-09-19T01:56:41.60891Z","caller":"traceutil/trace.go:171","msg":"trace[632529325] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5061; }","duration":"150.920314ms","start":"2026-09-19T01:56:41.457971Z","end":"2026-09-19T01:56:41.608891Z","steps":["trace[632529325] 'agreement among raft nodes before linearized reading' (duration: 150.66414ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:41.609138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.157727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-19T01:56:41.609245Z","caller":"traceutil/trace.go:171","msg":"trace[2129657652] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5061; }","duration":"145.3332ms","start":"2026-09-19T01:56:41.463882Z","end":"2026-09-19T01:56:41.609216Z","steps":["trace[2129657652] 'agreement among raft nodes before linearized reading' (duration: 145.009644ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:47.578916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.862668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:56:47.579046Z","caller":"traceutil/trace.go:171","msg":"trace[313977629] linearizableReadLoop","detail":"{readStateIndex:5359; appliedIndex:5357; }","duration":"232.891442ms","start":"2026-09-19T01:56:47.346135Z","end":"2026-09-19T01:56:47.579026Z","steps":["trace[313977629] 'read index received' (duration: 3.336146ms)","trace[313977629] 'applied index is now lower than readState.Index' (duration: 229.553616ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:56:47.579136Z","caller":"traceutil/trace.go:171","msg":"trace[1869438620] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"232.961063ms","start":"2026-09-19T01:56:47.346084Z","end":"2026-09-19T01:56:47.579045Z","steps":["trace[1869438620] 'process raft request' (duration: 87.874601ms)","trace[1869438620] 'compare' (duration: 144.590704ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:56:47.579299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.156207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1453"} {"level":"info","ts":"2026-09-19T01:56:47.57937Z","caller":"traceutil/trace.go:171","msg":"trace[202442314] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5156; }","duration":"233.255709ms","start":"2026-09-19T01:56:47.346105Z","end":"2026-09-19T01:56:47.579361Z","steps":["trace[202442314] 'agreement among raft nodes before linearized reading' (duration: 233.001074ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:47.751729Z","caller":"traceutil/trace.go:171","msg":"trace[1062551626] transaction","detail":"{read_only:false; response_revision:5159; number_of_response:1; }","duration":"163.779029ms","start":"2026-09-19T01:56:47.587915Z","end":"2026-09-19T01:56:47.751694Z","steps":["trace[1062551626] 'process raft request' (duration: 163.729608ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:47.751869Z","caller":"traceutil/trace.go:171","msg":"trace[461757363] transaction","detail":"{read_only:false; response_revision:5157; number_of_response:1; }","duration":"399.068662ms","start":"2026-09-19T01:56:47.352782Z","end":"2026-09-19T01:56:47.75185Z","steps":["trace[461757363] 'process raft request' (duration: 346.950648ms)","trace[461757363] 'compare' (duration: 51.573195ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:56:47.752009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:56:47.35275Z","time spent":"399.161944ms","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":997,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T01:56:47.752064Z","caller":"traceutil/trace.go:171","msg":"trace[546807315] linearizableReadLoop","detail":"{readStateIndex:5361; appliedIndex:5359; }","duration":"172.968246ms","start":"2026-09-19T01:56:47.579072Z","end":"2026-09-19T01:56:47.75204Z","steps":["trace[546807315] 'read index received' (duration: 120.709469ms)","trace[546807315] 'applied index is now lower than readState.Index' (duration: 52.256886ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:56:47.751789Z","caller":"traceutil/trace.go:171","msg":"trace[1374058920] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"387.802251ms","start":"2026-09-19T01:56:47.363934Z","end":"2026-09-19T01:56:47.751736Z","steps":["trace[1374058920] 'process raft request' (duration: 387.598337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:47.75223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.940776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T01:56:47.752237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:56:47.363905Z","time spent":"388.214648ms","remote":"127.0.0.1:42562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9620,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:56:47.752275Z","caller":"traceutil/trace.go:171","msg":"trace[851160923] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:5159; }","duration":"208.998357ms","start":"2026-09-19T01:56:47.543262Z","end":"2026-09-19T01:56:47.752261Z","steps":["trace[851160923] 'agreement among raft nodes before linearized reading' (duration: 208.884685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:47.752793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.528298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9895"} {"level":"info","ts":"2026-09-19T01:56:47.752837Z","caller":"traceutil/trace.go:171","msg":"trace[15783619] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5159; }","duration":"406.580249ms","start":"2026-09-19T01:56:47.346246Z","end":"2026-09-19T01:56:47.752826Z","steps":["trace[15783619] 'agreement among raft nodes before linearized reading' (duration: 406.435877ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:47.752864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:56:47.346242Z","time spent":"406.615629ms","remote":"127.0.0.1:42280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":9917,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-09-19T01:56:47.752862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.151672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-19T01:56:47.752912Z","caller":"traceutil/trace.go:171","msg":"trace[1553040512] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5159; }","duration":"406.238445ms","start":"2026-09-19T01:56:47.346661Z","end":"2026-09-19T01:56:47.752899Z","steps":["trace[1553040512] 'agreement among raft nodes before linearized reading' (duration: 406.018521ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:47.752955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:56:47.346647Z","time spent":"406.299006ms","remote":"127.0.0.1:42300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1307,"request content":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" "} {"level":"warn","ts":"2026-09-19T01:56:47.753392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.496402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-19T01:56:47.753439Z","caller":"traceutil/trace.go:171","msg":"trace[211825558] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5159; }","duration":"365.585565ms","start":"2026-09-19T01:56:47.387843Z","end":"2026-09-19T01:56:47.753428Z","steps":["trace[211825558] 'agreement among raft nodes before linearized reading' (duration: 365.441892ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:47.753474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:56:47.387812Z","time spent":"365.655245ms","remote":"127.0.0.1:42292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-19T01:56:47.753543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.337516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2362739"} {"level":"info","ts":"2026-09-19T01:56:47.753588Z","caller":"traceutil/trace.go:171","msg":"trace[817338455] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5159; }","duration":"287.436048ms","start":"2026-09-19T01:56:47.466141Z","end":"2026-09-19T01:56:47.753577Z","steps":["trace[817338455] 'agreement among raft nodes before linearized reading' (duration: 286.296489ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:47.906653Z","caller":"traceutil/trace.go:171","msg":"trace[914802321] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"156.966794ms","start":"2026-09-19T01:56:47.749602Z","end":"2026-09-19T01:56:47.906569Z","steps":["trace[914802321] 'process raft request' (duration: 118.580822ms)","trace[914802321] 'compare' (duration: 38.071807ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:56:47.906866Z","caller":"traceutil/trace.go:171","msg":"trace[359935399] linearizableReadLoop","detail":"{readStateIndex:5363; appliedIndex:5362; }","duration":"154.726755ms","start":"2026-09-19T01:56:47.752108Z","end":"2026-09-19T01:56:47.906834Z","steps":["trace[359935399] 'read index received' (duration: 116.093619ms)","trace[359935399] 'applied index is now lower than readState.Index' (duration: 38.630706ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:56:47.90722Z","caller":"traceutil/trace.go:171","msg":"trace[1592348787] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"132.964328ms","start":"2026-09-19T01:56:47.774233Z","end":"2026-09-19T01:56:47.907198Z","steps":["trace[1592348787] 'process raft request' (duration: 132.829255ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:47.907256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.561901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9635"} {"level":"info","ts":"2026-09-19T01:56:47.907305Z","caller":"traceutil/trace.go:171","msg":"trace[499371128] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5161; }","duration":"320.715283ms","start":"2026-09-19T01:56:47.586574Z","end":"2026-09-19T01:56:47.907289Z","steps":["trace[499371128] 'agreement among raft nodes before linearized reading' (duration: 320.462779ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:47.907379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:56:47.586553Z","time spent":"320.814004ms","remote":"127.0.0.1:42562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9657,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"info","ts":"2026-09-19T01:56:47.907417Z","caller":"traceutil/trace.go:171","msg":"trace[2014849385] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"142.370326ms","start":"2026-09-19T01:56:47.765028Z","end":"2026-09-19T01:56:47.907398Z","steps":["trace[2014849385] 'process raft request' (duration: 141.44727ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:47.907959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.342831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-19T01:56:47.908013Z","caller":"traceutil/trace.go:171","msg":"trace[475155212] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5162; }","duration":"150.442804ms","start":"2026-09-19T01:56:47.757557Z","end":"2026-09-19T01:56:47.907999Z","steps":["trace[475155212] 'agreement among raft nodes before linearized reading' (duration: 149.921155ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:47.908086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.094063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-09-19T01:56:47.908149Z","caller":"traceutil/trace.go:171","msg":"trace[1407570502] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5162; }","duration":"102.242445ms","start":"2026-09-19T01:56:47.805887Z","end":"2026-09-19T01:56:47.90813Z","steps":["trace[1407570502] 'agreement among raft nodes before linearized reading' (duration: 101.94034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:47.908259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.640307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-19T01:56:47.908321Z","caller":"traceutil/trace.go:171","msg":"trace[2123689554] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5162; }","duration":"140.730479ms","start":"2026-09-19T01:56:47.767555Z","end":"2026-09-19T01:56:47.908285Z","steps":["trace[2123689554] 'agreement among raft nodes before linearized reading' (duration: 140.623237ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:48.314632Z","caller":"traceutil/trace.go:171","msg":"trace[1861399992] transaction","detail":"{read_only:false; response_revision:5170; number_of_response:1; }","duration":"248.91544ms","start":"2026-09-19T01:56:48.065672Z","end":"2026-09-19T01:56:48.314588Z","steps":["trace[1861399992] 'process raft request' (duration: 227.002879ms)","trace[1861399992] 'compare' (duration: 21.751298ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:56:48.345904Z","caller":"traceutil/trace.go:171","msg":"trace[1961824224] transaction","detail":"{read_only:false; response_revision:5171; number_of_response:1; }","duration":"278.652855ms","start":"2026-09-19T01:56:48.067218Z","end":"2026-09-19T01:56:48.345871Z","steps":["trace[1961824224] 'process raft request' (duration: 278.319759ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:48.346057Z","caller":"traceutil/trace.go:171","msg":"trace[1806401123] linearizableReadLoop","detail":"{readStateIndex:5374; appliedIndex:5372; }","duration":"278.536593ms","start":"2026-09-19T01:56:48.067479Z","end":"2026-09-19T01:56:48.346015Z","steps":["trace[1806401123] 'read index received' (duration: 225.217179ms)","trace[1806401123] 'applied index is now lower than readState.Index' (duration: 53.315994ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:56:48.346314Z","caller":"traceutil/trace.go:171","msg":"trace[756333902] transaction","detail":"{read_only:false; response_revision:5172; number_of_response:1; }","duration":"266.353196ms","start":"2026-09-19T01:56:48.079907Z","end":"2026-09-19T01:56:48.34626Z","steps":["trace[756333902] 'process raft request' (duration: 265.861638ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:48.346577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.051021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1453"} {"level":"info","ts":"2026-09-19T01:56:48.346639Z","caller":"traceutil/trace.go:171","msg":"trace[1324558315] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5172; }","duration":"279.188734ms","start":"2026-09-19T01:56:48.067436Z","end":"2026-09-19T01:56:48.346625Z","steps":["trace[1324558315] 'agreement among raft nodes before linearized reading' (duration: 278.832108ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:48.372307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.145236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-09-19T01:56:48.372507Z","caller":"traceutil/trace.go:171","msg":"trace[1182276168] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5176; }","duration":"301.381691ms","start":"2026-09-19T01:56:48.071099Z","end":"2026-09-19T01:56:48.372481Z","steps":["trace[1182276168] 'agreement among raft nodes before linearized reading' (duration: 301.063566ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:48.372631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:56:48.071085Z","time spent":"301.527693ms","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5705,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-19T01:56:48.372986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.505216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-09-19T01:56:48.373102Z","caller":"traceutil/trace.go:171","msg":"trace[348624144] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:5176; }","duration":"303.657358ms","start":"2026-09-19T01:56:48.069416Z","end":"2026-09-19T01:56:48.373074Z","steps":["trace[348624144] 'agreement among raft nodes before linearized reading' (duration: 303.245651ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:48.373164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:56:48.069406Z","time spent":"303.745789ms","remote":"127.0.0.1:42188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":718,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" "} {"level":"warn","ts":"2026-09-19T01:56:48.376902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.234646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-09-19T01:56:48.377011Z","caller":"traceutil/trace.go:171","msg":"trace[226052335] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:5176; }","duration":"144.423159ms","start":"2026-09-19T01:56:48.232558Z","end":"2026-09-19T01:56:48.376981Z","steps":["trace[226052335] 'agreement among raft nodes before linearized reading' (duration: 139.936023ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:48.600304Z","caller":"traceutil/trace.go:171","msg":"trace[1758627341] linearizableReadLoop","detail":"{readStateIndex:5382; appliedIndex:5380; }","duration":"194.707251ms","start":"2026-09-19T01:56:48.405577Z","end":"2026-09-19T01:56:48.600284Z","steps":["trace[1758627341] 'read index received' (duration: 169.697167ms)","trace[1758627341] 'applied index is now lower than readState.Index' (duration: 25.008964ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:56:48.600504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.214843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-09-19T01:56:48.600531Z","caller":"traceutil/trace.go:171","msg":"trace[284094485] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:5179; }","duration":"220.286255ms","start":"2026-09-19T01:56:48.380237Z","end":"2026-09-19T01:56:48.600523Z","steps":["trace[284094485] 'agreement among raft nodes before linearized reading' (duration: 220.162073ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:48.60057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.954872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-09-19T01:56:48.600667Z","caller":"traceutil/trace.go:171","msg":"trace[1247586537] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5179; }","duration":"219.086285ms","start":"2026-09-19T01:56:48.381559Z","end":"2026-09-19T01:56:48.600645Z","steps":["trace[1247586537] 'agreement among raft nodes before linearized reading' (duration: 218.84026ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:48.600956Z","caller":"traceutil/trace.go:171","msg":"trace[450244845] transaction","detail":"{read_only:false; response_revision:5179; number_of_response:1; }","duration":"216.651603ms","start":"2026-09-19T01:56:48.384294Z","end":"2026-09-19T01:56:48.600945Z","steps":["trace[450244845] 'process raft request' (duration: 215.884369ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:48.60111Z","caller":"traceutil/trace.go:171","msg":"trace[1293409782] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"218.923731ms","start":"2026-09-19T01:56:48.38217Z","end":"2026-09-19T01:56:48.601094Z","steps":["trace[1293409782] 'process raft request' (duration: 193.202715ms)","trace[1293409782] 'compare' (duration: 24.429275ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:56:56.629032Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2856} {"level":"info","ts":"2026-09-19T01:56:56.747472Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2856,"took":"116.723227ms","hash":3945676577,"current-db-size-bytes":51220480,"current-db-size":"51 MB","current-db-size-in-use-bytes":27844608,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-19T01:56:56.747554Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3945676577,"revision":2856,"compact-revision":-1} {"level":"warn","ts":"2026-09-19T01:57:00.671904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.458479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:57:00.672039Z","caller":"traceutil/trace.go:171","msg":"trace[1305420466] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"262.832934ms","start":"2026-09-19T01:57:00.409169Z","end":"2026-09-19T01:57:00.672002Z","steps":["trace[1305420466] 'process raft request' (duration: 141.1775ms)","trace[1305420466] 'compare' (duration: 121.327708ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:57:00.891408Z","caller":"traceutil/trace.go:171","msg":"trace[1242383950] linearizableReadLoop","detail":"{readStateIndex:5548; appliedIndex:5547; }","duration":"169.505436ms","start":"2026-09-19T01:57:00.721872Z","end":"2026-09-19T01:57:00.891378Z","steps":["trace[1242383950] 'read index received' (duration: 169.19254ms)","trace[1242383950] 'applied index is now lower than readState.Index' (duration: 311.826µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:57:00.891557Z","caller":"traceutil/trace.go:171","msg":"trace[1171485747] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"481.444744ms","start":"2026-09-19T01:57:00.410101Z","end":"2026-09-19T01:57:00.891546Z","steps":["trace[1171485747] 'process raft request' (duration: 481.050767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:57:00.891714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:57:00.410088Z","time spent":"481.509095ms","remote":"127.0.0.1:42588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10497,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:57:00.892017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.052005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-19T01:57:00.892131Z","caller":"traceutil/trace.go:171","msg":"trace[343409716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5338; }","duration":"170.193018ms","start":"2026-09-19T01:57:00.721914Z","end":"2026-09-19T01:57:00.892107Z","steps":["trace[343409716] 'agreement among raft nodes before linearized reading' (duration: 169.932934ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:57:00.892278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.395651ms","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-09-19T01:57:00.892344Z","caller":"traceutil/trace.go:171","msg":"trace[867066050] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5338; }","duration":"170.501503ms","start":"2026-09-19T01:57:00.721824Z","end":"2026-09-19T01:57:00.892325Z","steps":["trace[867066050] 'agreement among raft nodes before linearized reading' (duration: 170.305339ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:57:01.016421Z","caller":"traceutil/trace.go:171","msg":"trace[912696769] transaction","detail":"{read_only:false; response_revision:5340; number_of_response:1; }","duration":"115.104874ms","start":"2026-09-19T01:57:00.901294Z","end":"2026-09-19T01:57:01.016399Z","steps":["trace[912696769] 'process raft request' (duration: 114.610455ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:57:01.016398Z","caller":"traceutil/trace.go:171","msg":"trace[1854736303] transaction","detail":"{read_only:false; response_revision:5341; number_of_response:1; }","duration":"109.273134ms","start":"2026-09-19T01:57:00.90709Z","end":"2026-09-19T01:57:01.016363Z","steps":["trace[1854736303] 'process raft request' (duration: 109.138371ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:57:01.090305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.308732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-09-19T01:57:01.090359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.419473ms","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-09-19T01:57:01.090431Z","caller":"traceutil/trace.go:171","msg":"trace[746063075] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5342; }","duration":"129.556724ms","start":"2026-09-19T01:57:00.960856Z","end":"2026-09-19T01:57:01.090413Z","steps":["trace[746063075] 'agreement among raft nodes before linearized reading' (duration: 129.27988ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:57:01.090438Z","caller":"traceutil/trace.go:171","msg":"trace[2145721209] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5342; }","duration":"113.447514ms","start":"2026-09-19T01:57:00.976923Z","end":"2026-09-19T01:57:01.09037Z","steps":["trace[2145721209] 'agreement among raft nodes before linearized reading' (duration: 113.203861ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:57:49.682227Z","caller":"traceutil/trace.go:171","msg":"trace[487253635] transaction","detail":"{read_only:false; response_revision:5775; number_of_response:1; }","duration":"122.289668ms","start":"2026-09-19T01:57:49.559886Z","end":"2026-09-19T01:57:49.682175Z","steps":["trace[487253635] 'process raft request' (duration: 105.945882ms)","trace[487253635] 'compare' (duration: 15.988709ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:57:55.655719Z","caller":"traceutil/trace.go:171","msg":"trace[1025481789] transaction","detail":"{read_only:false; response_revision:5829; number_of_response:1; }","duration":"140.597998ms","start":"2026-09-19T01:57:55.515094Z","end":"2026-09-19T01:57:55.655692Z","steps":["trace[1025481789] 'process raft request' (duration: 132.278813ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:01:56.635405Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5302} {"level":"info","ts":"2026-09-19T02:01:56.707531Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5302,"took":"70.755209ms","hash":1721718823,"current-db-size-bytes":51220480,"current-db-size":"51 MB","current-db-size-in-use-bytes":19279872,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-19T02:01:56.707591Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1721718823,"revision":5302,"compact-revision":2856}