{"level":"warn","ts":"2026-08-24T20:32:43.504999Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T20:32:43.505083Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.177:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.177:2380","--initial-cluster=instance=https://199.204.45.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.177:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-24T20:32:43.505153Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T20:32:43.50516Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-08-24T20:32:43.505176Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-24T20:32:43.505893Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"]} {"level":"info","ts":"2026-08-24T20:32:43.50599Z","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://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.177:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-24T20:32:43.520477Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.276166ms"} {"level":"info","ts":"2026-08-24T20:32:43.530029Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7c53a18098324dc9","cluster-id":"4717e8aa221973c8"} {"level":"info","ts":"2026-08-24T20:32:43.530155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T20:32:43.5302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 0"} {"level":"info","ts":"2026-08-24T20:32:43.53022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7c53a18098324dc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T20:32:43.53026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 1"} {"level":"info","ts":"2026-08-24T20:32:43.530322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"warn","ts":"2026-08-24T20:32:43.537317Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T20:32:43.538826Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T20:32:43.540113Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-24T20:32:43.542372Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7c53a18098324dc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T20:32:43.548191Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T20:32:43.548409Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T20:32:43.548366Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7c53a18098324dc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T20:32:43.548602Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T20:32:43.551015Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-24T20:32:43.551162Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-08-24T20:32:43.551212Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-08-24T20:32:43.551364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"info","ts":"2026-08-24T20:32:43.551393Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7c53a18098324dc9","initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T20:32:43.551469Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T20:32:43.552401Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","added-peer-id":"7c53a18098324dc9","added-peer-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-08-24T20:32:43.731578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T20:32:43.731647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T20:32:43.731687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgPreVoteResp from 7c53a18098324dc9 at term 1"} {"level":"info","ts":"2026-08-24T20:32:43.731709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became candidate at term 2"} {"level":"info","ts":"2026-08-24T20:32:43.731737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgVoteResp from 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-08-24T20:32:43.73176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became leader at term 2"} {"level":"info","ts":"2026-08-24T20:32:43.73178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7c53a18098324dc9 elected leader 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-08-24T20:32:43.732602Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:32:43.732814Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T20:32:43.732907Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T20:32:43.733453Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:32:43.733586Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:32:43.733628Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:32:43.734132Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T20:32:43.734195Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T20:32:43.736803Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T20:32:43.736844Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.177:2379"} {"level":"info","ts":"2026-08-24T20:32:43.732769Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7c53a18098324dc9","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.177:2379]}","request-path":"/0/members/7c53a18098324dc9/attributes","cluster-id":"4717e8aa221973c8","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T20:33:13.135354Z","caller":"traceutil/trace.go:171","msg":"trace[452365055] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"100.895074ms","start":"2026-08-24T20:33:13.034436Z","end":"2026-08-24T20:33:13.135331Z","steps":["trace[452365055] 'process raft request' (duration: 100.763974ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:20.304709Z","caller":"traceutil/trace.go:171","msg":"trace[937235940] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"146.090352ms","start":"2026-08-24T20:33:20.158587Z","end":"2026-08-24T20:33:20.304678Z","steps":["trace[937235940] 'process raft request' (duration: 145.940423ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:20.309914Z","caller":"traceutil/trace.go:171","msg":"trace[1372106531] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"147.350336ms","start":"2026-08-24T20:33:20.162547Z","end":"2026-08-24T20:33:20.309897Z","steps":["trace[1372106531] 'process raft request' (duration: 147.181317ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:20.310078Z","caller":"traceutil/trace.go:171","msg":"trace[681350463] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"147.574695ms","start":"2026-08-24T20:33:20.162494Z","end":"2026-08-24T20:33:20.310069Z","steps":["trace[681350463] 'process raft request' (duration: 147.111877ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:20.312887Z","caller":"traceutil/trace.go:171","msg":"trace[1325486702] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"139.260519ms","start":"2026-08-24T20:33:20.173452Z","end":"2026-08-24T20:33:20.312712Z","steps":["trace[1325486702] 'process raft request' (duration: 136.36861ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:20.313138Z","caller":"traceutil/trace.go:171","msg":"trace[2059536750] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"142.510246ms","start":"2026-08-24T20:33:20.170599Z","end":"2026-08-24T20:33:20.313109Z","steps":["trace[2059536750] 'process raft request' (duration: 139.18675ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:20.313127Z","caller":"traceutil/trace.go:171","msg":"trace[1098041385] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"130.387432ms","start":"2026-08-24T20:33:20.182698Z","end":"2026-08-24T20:33:20.313085Z","steps":["trace[1098041385] 'process raft request' (duration: 127.149005ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:20.313506Z","caller":"traceutil/trace.go:171","msg":"trace[509781080] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"122.995541ms","start":"2026-08-24T20:33:20.190401Z","end":"2026-08-24T20:33:20.313396Z","steps":["trace[509781080] 'process raft request' (duration: 119.542805ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:20.313576Z","caller":"traceutil/trace.go:171","msg":"trace[1755638573] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"126.668908ms","start":"2026-08-24T20:33:20.186612Z","end":"2026-08-24T20:33:20.313281Z","steps":["trace[1755638573] 'process raft request' (duration: 123.262841ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:20.315157Z","caller":"traceutil/trace.go:171","msg":"trace[422417690] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"120.170073ms","start":"2026-08-24T20:33:20.194644Z","end":"2026-08-24T20:33:20.314814Z","steps":["trace[422417690] 'process raft request' (duration: 115.599001ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:20.315223Z","caller":"traceutil/trace.go:171","msg":"trace[1922645812] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"120.459471ms","start":"2026-08-24T20:33:20.194739Z","end":"2026-08-24T20:33:20.315198Z","steps":["trace[1922645812] 'process raft request' (duration: 120.050323ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:26.471016Z","caller":"traceutil/trace.go:171","msg":"trace[73808647] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"260.350063ms","start":"2026-08-24T20:33:26.21063Z","end":"2026-08-24T20:33:26.47098Z","steps":["trace[73808647] 'process raft request' (duration: 259.798194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:33:26.750949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.7307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-24T20:33:26.751036Z","caller":"traceutil/trace.go:171","msg":"trace[1946170626] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"220.920912ms","start":"2026-08-24T20:33:26.530103Z","end":"2026-08-24T20:33:26.751024Z","steps":["trace[1946170626] 'read index received' (duration: 14.676223ms)","trace[1946170626] 'applied index is now lower than readState.Index' (duration: 206.243619ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:33:26.751058Z","caller":"traceutil/trace.go:171","msg":"trace[212435976] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"222.069519ms","start":"2026-08-24T20:33:26.528968Z","end":"2026-08-24T20:33:26.751037Z","steps":["trace[212435976] 'process raft request' (duration: 15.83924ms)","trace[212435976] 'compare' (duration: 205.406821ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:33:26.75117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.060891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tl4kb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-24T20:33:26.751188Z","caller":"traceutil/trace.go:171","msg":"trace[1103371143] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tl4kb; range_end:; response_count:1; response_revision:591; }","duration":"221.108351ms","start":"2026-08-24T20:33:26.530074Z","end":"2026-08-24T20:33:26.751182Z","steps":["trace[1103371143] 'agreement among raft nodes before linearized reading' (duration: 220.982052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:33:27.296897Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605187362774847286,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-24T20:33:27.376882Z","caller":"traceutil/trace.go:171","msg":"trace[1390027212] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"610.942169ms","start":"2026-08-24T20:33:26.765906Z","end":"2026-08-24T20:33:27.376848Z","steps":["trace[1390027212] 'process raft request' (duration: 568.28924ms)","trace[1390027212] 'compare' (duration: 41.911572ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:33:27.378136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:33:26.765896Z","time spent":"611.047358ms","remote":"127.0.0.1:51316","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-08-24T20:33:27.378316Z","caller":"traceutil/trace.go:171","msg":"trace[1119106657] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:616; }","duration":"581.837467ms","start":"2026-08-24T20:33:26.796461Z","end":"2026-08-24T20:33:27.378298Z","steps":["trace[1119106657] 'read index received' (duration: 537.742325ms)","trace[1119106657] 'applied index is now lower than readState.Index' (duration: 44.094282ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:33:27.378458Z","caller":"traceutil/trace.go:171","msg":"trace[1270127335] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"234.773572ms","start":"2026-08-24T20:33:27.143672Z","end":"2026-08-24T20:33:27.378445Z","steps":["trace[1270127335] 'process raft request' (duration: 233.380508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:33:27.378526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.045687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:33:27.37862Z","caller":"traceutil/trace.go:171","msg":"trace[1060907133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"582.187767ms","start":"2026-08-24T20:33:26.796409Z","end":"2026-08-24T20:33:27.378597Z","steps":["trace[1060907133] 'agreement among raft nodes before linearized reading' (duration: 582.000987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:33:27.378677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:33:26.796388Z","time spent":"582.277337ms","remote":"127.0.0.1:51172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T20:33:27.378864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.297147ms","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-08-24T20:33:27.378945Z","caller":"traceutil/trace.go:171","msg":"trace[731292700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"141.406886ms","start":"2026-08-24T20:33:27.237521Z","end":"2026-08-24T20:33:27.378928Z","steps":["trace[731292700] 'agreement among raft nodes before linearized reading' (duration: 141.158677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:33:27.378886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.629152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-24T20:33:27.379115Z","caller":"traceutil/trace.go:171","msg":"trace[1017130327] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:593; }","duration":"549.878471ms","start":"2026-08-24T20:33:26.829218Z","end":"2026-08-24T20:33:27.379097Z","steps":["trace[1017130327] 'agreement among raft nodes before linearized reading' (duration: 549.346093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:33:27.379231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:33:26.829207Z","time spent":"550.009001ms","remote":"127.0.0.1:51198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.177\" "} {"level":"warn","ts":"2026-08-24T20:33:27.379131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.257701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-24T20:33:27.381199Z","caller":"traceutil/trace.go:171","msg":"trace[1709202775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"517.354913ms","start":"2026-08-24T20:33:26.863813Z","end":"2026-08-24T20:33:27.381168Z","steps":["trace[1709202775] 'agreement among raft nodes before linearized reading' (duration: 514.894363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:33:27.381261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:33:26.863786Z","time spent":"517.458393ms","remote":"127.0.0.1:51392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-24T20:33:53.664853Z","caller":"traceutil/trace.go:171","msg":"trace[1152722174] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"137.096674ms","start":"2026-08-24T20:33:53.527732Z","end":"2026-08-24T20:33:53.664829Z","steps":["trace[1152722174] 'process raft request' (duration: 136.946536ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:53.745119Z","caller":"traceutil/trace.go:171","msg":"trace[1753702846] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"217.357507ms","start":"2026-08-24T20:33:53.527731Z","end":"2026-08-24T20:33:53.745089Z","steps":["trace[1753702846] 'process raft request' (duration: 217.116793ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:54.07517Z","caller":"traceutil/trace.go:171","msg":"trace[46857721] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"223.523927ms","start":"2026-08-24T20:33:53.851623Z","end":"2026-08-24T20:33:54.075147Z","steps":["trace[46857721] 'process raft request' (duration: 212.713403ms)","trace[46857721] 'compare' (duration: 10.627343ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:34:35.0534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.48919ms","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-08-24T20:34:35.053482Z","caller":"traceutil/trace.go:171","msg":"trace[2070719211] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1493; }","duration":"101.604004ms","start":"2026-08-24T20:34:34.951862Z","end":"2026-08-24T20:34:35.053466Z","steps":["trace[2070719211] 'range keys from in-memory index tree' (duration: 101.370646ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:34:47.224452Z","caller":"traceutil/trace.go:171","msg":"trace[1568772416] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"119.249421ms","start":"2026-08-24T20:34:47.105179Z","end":"2026-08-24T20:34:47.224428Z","steps":["trace[1568772416] 'process raft request' (duration: 118.770267ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:34:47.338889Z","caller":"traceutil/trace.go:171","msg":"trace[553429840] transaction","detail":"{read_only:false; response_revision:1686; number_of_response:1; }","duration":"110.143653ms","start":"2026-08-24T20:34:47.228615Z","end":"2026-08-24T20:34:47.338759Z","steps":["trace[553429840] 'process raft request' (duration: 109.869875ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:34:54.101053Z","caller":"traceutil/trace.go:171","msg":"trace[253690989] linearizableReadLoop","detail":"{readStateIndex:1869; appliedIndex:1868; }","duration":"113.004335ms","start":"2026-08-24T20:34:53.988011Z","end":"2026-08-24T20:34:54.101015Z","steps":["trace[253690989] 'read index received' (duration: 112.802ms)","trace[253690989] 'applied index is now lower than readState.Index' (duration: 201.415µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:34:54.101132Z","caller":"traceutil/trace.go:171","msg":"trace[1077220118] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"132.490138ms","start":"2026-08-24T20:34:53.968606Z","end":"2026-08-24T20:34:54.101096Z","steps":["trace[1077220118] 'process raft request' (duration: 132.265272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:34:54.101282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.241512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3c021181-4f9f-4ca4-9c02-ffc83bb620ca\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-24T20:34:54.101333Z","caller":"traceutil/trace.go:171","msg":"trace[524017045] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3c021181-4f9f-4ca4-9c02-ffc83bb620ca; range_end:; response_count:1; response_revision:1794; }","duration":"113.346734ms","start":"2026-08-24T20:34:53.987975Z","end":"2026-08-24T20:34:54.101322Z","steps":["trace[524017045] 'agreement among raft nodes before linearized reading' (duration: 113.150159ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:34:54.530729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.282033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T20:34:54.530871Z","caller":"traceutil/trace.go:171","msg":"trace[619029990] linearizableReadLoop","detail":"{readStateIndex:1870; appliedIndex:1869; }","duration":"410.589218ms","start":"2026-08-24T20:34:54.120252Z","end":"2026-08-24T20:34:54.530841Z","steps":["trace[619029990] 'read index received' (duration: 296.079648ms)","trace[619029990] 'applied index is now lower than readState.Index' (duration: 114.50825ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:34:54.531268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.010488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-24T20:34:54.531318Z","caller":"traceutil/trace.go:171","msg":"trace[395975741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1795; }","duration":"411.07781ms","start":"2026-08-24T20:34:54.120226Z","end":"2026-08-24T20:34:54.531304Z","steps":["trace[395975741] 'agreement among raft nodes before linearized reading' (duration: 410.733131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:34:54.531313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.811134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3c021181-4f9f-4ca4-9c02-ffc83bb620ca\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-08-24T20:34:54.531232Z","caller":"traceutil/trace.go:171","msg":"trace[432333145] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"421.838759ms","start":"2026-08-24T20:34:54.109119Z","end":"2026-08-24T20:34:54.530958Z","steps":["trace[432333145] 'process raft request' (duration: 307.242567ms)","trace[432333145] 'compare' (duration: 114.153379ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:34:54.531369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:34:54.12021Z","time spent":"411.147902ms","remote":"127.0.0.1:51392","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-08-24T20:34:54.531372Z","caller":"traceutil/trace.go:171","msg":"trace[974375510] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3c021181-4f9f-4ca4-9c02-ffc83bb620ca; range_end:; response_count:1; response_revision:1795; }","duration":"314.916127ms","start":"2026-08-24T20:34:54.216442Z","end":"2026-08-24T20:34:54.531358Z","steps":["trace[974375510] 'agreement among raft nodes before linearized reading' (duration: 314.817004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:34:54.531504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:34:54.109095Z","time spent":"422.28223ms","remote":"127.0.0.1:51316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T20:34:54.531532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:34:54.216422Z","time spent":"315.076611ms","remote":"127.0.0.1:51316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3766,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3c021181-4f9f-4ca4-9c02-ffc83bb620ca\" "} {"level":"warn","ts":"2026-08-24T20:34:54.531615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.264376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-24T20:34:54.531683Z","caller":"traceutil/trace.go:171","msg":"trace[659752445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1795; }","duration":"273.354329ms","start":"2026-08-24T20:34:54.25831Z","end":"2026-08-24T20:34:54.531665Z","steps":["trace[659752445] 'agreement among raft nodes before linearized reading' (duration: 273.215645ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:34:54.531652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.909931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-24T20:34:54.531764Z","caller":"traceutil/trace.go:171","msg":"trace[1505615205] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1795; }","duration":"159.068915ms","start":"2026-08-24T20:34:54.372667Z","end":"2026-08-24T20:34:54.531736Z","steps":["trace[1505615205] 'agreement among raft nodes before linearized reading' (duration: 158.747086ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:34:54.656501Z","caller":"traceutil/trace.go:171","msg":"trace[1918969377] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"120.68995ms","start":"2026-08-24T20:34:54.535784Z","end":"2026-08-24T20:34:54.656474Z","steps":["trace[1918969377] 'process raft request' (duration: 109.12973ms)","trace[1918969377] 'compare' (duration: 11.333704ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:34:54.656852Z","caller":"traceutil/trace.go:171","msg":"trace[721942207] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"120.500805ms","start":"2026-08-24T20:34:54.536261Z","end":"2026-08-24T20:34:54.656762Z","steps":["trace[721942207] 'process raft request' (duration: 120.112644ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:34:54.955355Z","caller":"traceutil/trace.go:171","msg":"trace[1993329149] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"103.583775ms","start":"2026-08-24T20:34:54.851749Z","end":"2026-08-24T20:34:54.955333Z","steps":["trace[1993329149] 'process raft request' (duration: 103.420811ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:35:03.714156Z","caller":"traceutil/trace.go:171","msg":"trace[989738812] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1962; }","duration":"257.651274ms","start":"2026-08-24T20:35:03.456416Z","end":"2026-08-24T20:35:03.714067Z","steps":["trace[989738812] 'read index received' (duration: 257.49715ms)","trace[989738812] 'applied index is now lower than readState.Index' (duration: 153.204µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:35:03.714568Z","caller":"traceutil/trace.go:171","msg":"trace[90727690] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"263.148561ms","start":"2026-08-24T20:35:03.451366Z","end":"2026-08-24T20:35:03.714515Z","steps":["trace[90727690] 'process raft request' (duration: 262.572778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:03.714643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.205816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-08-24T20:35:03.714711Z","caller":"traceutil/trace.go:171","msg":"trace[138015151] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1885; }","duration":"258.298988ms","start":"2026-08-24T20:35:03.456393Z","end":"2026-08-24T20:35:03.714692Z","steps":["trace[138015151] 'agreement among raft nodes before linearized reading' (duration: 257.930579ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:35:03.751752Z","caller":"traceutil/trace.go:171","msg":"trace[1943175000] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"184.463706ms","start":"2026-08-24T20:35:03.567263Z","end":"2026-08-24T20:35:03.751727Z","steps":["trace[1943175000] 'process raft request' (duration: 184.16526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:03.751898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.547129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-24T20:35:03.75198Z","caller":"traceutil/trace.go:171","msg":"trace[345074024] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1886; }","duration":"159.708103ms","start":"2026-08-24T20:35:03.592248Z","end":"2026-08-24T20:35:03.751957Z","steps":["trace[345074024] 'agreement among raft nodes before linearized reading' (duration: 159.389895ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:09.844917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.437084ms","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-08-24T20:35:09.845007Z","caller":"traceutil/trace.go:171","msg":"trace[1616932364] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1921; }","duration":"106.575147ms","start":"2026-08-24T20:35:09.73841Z","end":"2026-08-24T20:35:09.844985Z","steps":["trace[1616932364] 'range keys from in-memory index tree' (duration: 106.23758ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:35:28.531418Z","caller":"traceutil/trace.go:171","msg":"trace[961297836] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"184.436983ms","start":"2026-08-24T20:35:28.346946Z","end":"2026-08-24T20:35:28.531383Z","steps":["trace[961297836] 'process raft request' (duration: 183.75547ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:35:28.699766Z","caller":"traceutil/trace.go:171","msg":"trace[2037168872] linearizableReadLoop","detail":"{readStateIndex:2121; appliedIndex:2120; }","duration":"100.287706ms","start":"2026-08-24T20:35:28.599453Z","end":"2026-08-24T20:35:28.69974Z","steps":["trace[2037168872] 'read index received' (duration: 99.96391ms)","trace[2037168872] 'applied index is now lower than readState.Index' (duration: 322.906µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:35:28.69993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.466789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:35:28.699971Z","caller":"traceutil/trace.go:171","msg":"trace[499411831] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2038; }","duration":"100.591001ms","start":"2026-08-24T20:35:28.599369Z","end":"2026-08-24T20:35:28.69996Z","steps":["trace[499411831] 'agreement among raft nodes before linearized reading' (duration: 100.473929ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:35:28.699826Z","caller":"traceutil/trace.go:171","msg":"trace[1031783315] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"158.385052ms","start":"2026-08-24T20:35:28.541384Z","end":"2026-08-24T20:35:28.699769Z","steps":["trace[1031783315] 'process raft request' (duration: 158.105717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:29.067711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.673015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T20:35:29.06803Z","caller":"traceutil/trace.go:171","msg":"trace[656658739] linearizableReadLoop","detail":"{readStateIndex:2124; appliedIndex:2122; }","duration":"166.343547ms","start":"2026-08-24T20:35:28.901664Z","end":"2026-08-24T20:35:29.068008Z","steps":["trace[656658739] 'read index received' (duration: 7.199224ms)","trace[656658739] 'applied index is now lower than readState.Index' (duration: 159.142803ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:35:29.068189Z","caller":"traceutil/trace.go:171","msg":"trace[1920298871] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"198.525995ms","start":"2026-08-24T20:35:28.869648Z","end":"2026-08-24T20:35:29.068174Z","steps":["trace[1920298871] 'process raft request' (duration: 39.26479ms)","trace[1920298871] 'compare' (duration: 158.482852ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:35:29.07009Z","caller":"traceutil/trace.go:171","msg":"trace[1436356660] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"200.243434ms","start":"2026-08-24T20:35:28.869789Z","end":"2026-08-24T20:35:29.070033Z","steps":["trace[1436356660] 'process raft request' (duration: 198.153208ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:29.070169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.458843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T20:35:29.070332Z","caller":"traceutil/trace.go:171","msg":"trace[1441526943] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2041; }","duration":"168.651807ms","start":"2026-08-24T20:35:28.901651Z","end":"2026-08-24T20:35:29.070302Z","steps":["trace[1441526943] 'agreement among raft nodes before linearized reading' (duration: 168.316591ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:29.501909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.434488ms","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-08-24T20:35:29.501981Z","caller":"traceutil/trace.go:171","msg":"trace[1078546532] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2041; }","duration":"319.996107ms","start":"2026-08-24T20:35:29.181965Z","end":"2026-08-24T20:35:29.501961Z","steps":["trace[1078546532] 'range keys from in-memory index tree' (duration: 319.223205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:29.502017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:35:29.181936Z","time spent":"320.069479ms","remote":"127.0.0.1:51392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-24T20:35:29.509828Z","caller":"traceutil/trace.go:171","msg":"trace[676874852] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"165.222989ms","start":"2026-08-24T20:35:29.344575Z","end":"2026-08-24T20:35:29.509798Z","steps":["trace[676874852] 'process raft request' (duration: 165.032856ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:35:29.895072Z","caller":"traceutil/trace.go:171","msg":"trace[108570002] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"515.052977ms","start":"2026-08-24T20:35:29.379984Z","end":"2026-08-24T20:35:29.895037Z","steps":["trace[108570002] 'process raft request' (duration: 514.779402ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:35:29.895117Z","caller":"traceutil/trace.go:171","msg":"trace[958842816] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"387.952122ms","start":"2026-08-24T20:35:29.507131Z","end":"2026-08-24T20:35:29.895083Z","steps":["trace[958842816] 'process raft request' (duration: 387.833099ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:29.89529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:35:29.379946Z","time spent":"515.224381ms","remote":"127.0.0.1:51392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T20:35:29.895288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:35:29.507101Z","time spent":"388.093054ms","remote":"127.0.0.1:51392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T20:35:30.296493Z","caller":"traceutil/trace.go:171","msg":"trace[1619210903] transaction","detail":"{read_only:false; response_revision:2045; number_of_response:1; }","duration":"381.438346ms","start":"2026-08-24T20:35:29.915014Z","end":"2026-08-24T20:35:30.296453Z","steps":["trace[1619210903] 'process raft request' (duration: 345.742261ms)","trace[1619210903] 'compare' (duration: 35.323739ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:35:30.296483Z","caller":"traceutil/trace.go:171","msg":"trace[380193735] linearizableReadLoop","detail":"{readStateIndex:2128; appliedIndex:2127; }","duration":"353.905046ms","start":"2026-08-24T20:35:29.942544Z","end":"2026-08-24T20:35:30.296449Z","steps":["trace[380193735] 'read index received' (duration: 318.134599ms)","trace[380193735] 'applied index is now lower than readState.Index' (duration: 35.769007ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:35:30.296667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.124089ms","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":"warn","ts":"2026-08-24T20:35:30.29667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:35:29.914996Z","time spent":"381.567669ms","remote":"127.0.0.1:51392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T20:35:30.296704Z","caller":"traceutil/trace.go:171","msg":"trace[1400890943] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2045; }","duration":"354.198951ms","start":"2026-08-24T20:35:29.942497Z","end":"2026-08-24T20:35:30.296695Z","steps":["trace[1400890943] 'agreement among raft nodes before linearized reading' (duration: 354.040398ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:30.296729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:35:29.942478Z","time spent":"354.246091ms","remote":"127.0.0.1:51392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-24T20:35:30.296782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.289972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-08-24T20:35:30.296835Z","caller":"traceutil/trace.go:171","msg":"trace[1113749139] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2045; }","duration":"347.363443ms","start":"2026-08-24T20:35:29.949451Z","end":"2026-08-24T20:35:30.296814Z","steps":["trace[1113749139] 'agreement among raft nodes before linearized reading' (duration: 347.21002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:30.296874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:35:29.94944Z","time spent":"347.424054ms","remote":"127.0.0.1:51392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":592,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-08-24T20:35:30.297061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.749991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-24T20:35:30.297095Z","caller":"traceutil/trace.go:171","msg":"trace[1264562674] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2045; }","duration":"219.805301ms","start":"2026-08-24T20:35:30.07728Z","end":"2026-08-24T20:35:30.297085Z","steps":["trace[1264562674] 'agreement among raft nodes before linearized reading' (duration: 219.701809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:30.890144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.687596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-24T20:35:30.890314Z","caller":"traceutil/trace.go:171","msg":"trace[78022797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2047; }","duration":"354.91445ms","start":"2026-08-24T20:35:30.535369Z","end":"2026-08-24T20:35:30.890283Z","steps":["trace[78022797] 'range keys from in-memory index tree' (duration: 354.431352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:30.890378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:35:30.535346Z","time spent":"355.015151ms","remote":"127.0.0.1:51392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-24T20:36:40.669222Z","caller":"traceutil/trace.go:171","msg":"trace[1097784502] transaction","detail":"{read_only:false; response_revision:2519; number_of_response:1; }","duration":"100.000346ms","start":"2026-08-24T20:36:40.569197Z","end":"2026-08-24T20:36:40.669197Z","steps":["trace[1097784502] 'process raft request' (duration: 99.854365ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:37:13.743442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.553204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T20:37:13.743599Z","caller":"traceutil/trace.go:171","msg":"trace[779976653] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2701; }","duration":"154.784586ms","start":"2026-08-24T20:37:13.588788Z","end":"2026-08-24T20:37:13.743573Z","steps":["trace[779976653] 'count revisions from in-memory index tree' (duration: 154.443443ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:37:13.949487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.065255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ced832933173ba\" ","response":"range_response_count:1 size:777"} {"level":"info","ts":"2026-08-24T20:37:13.949755Z","caller":"traceutil/trace.go:171","msg":"trace[927724650] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ced832933173ba; range_end:; response_count:1; response_revision:2704; }","duration":"105.272977ms","start":"2026-08-24T20:37:13.844379Z","end":"2026-08-24T20:37:13.949652Z","steps":["trace[927724650] 'range keys from in-memory index tree' (duration: 104.875993ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:37:14.115557Z","caller":"traceutil/trace.go:171","msg":"trace[1309379666] transaction","detail":"{read_only:false; response_revision:2705; number_of_response:1; }","duration":"162.775351ms","start":"2026-08-24T20:37:13.952752Z","end":"2026-08-24T20:37:14.115527Z","steps":["trace[1309379666] 'process raft request' (duration: 160.996138ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:07.404631Z","caller":"traceutil/trace.go:171","msg":"trace[1833546137] transaction","detail":"{read_only:false; response_revision:4415; number_of_response:1; }","duration":"123.564207ms","start":"2026-08-24T20:41:07.281036Z","end":"2026-08-24T20:41:07.4046Z","steps":["trace[1833546137] 'process raft request' (duration: 123.496756ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:07.404736Z","caller":"traceutil/trace.go:171","msg":"trace[1918522540] transaction","detail":"{read_only:false; response_revision:4413; number_of_response:1; }","duration":"160.070801ms","start":"2026-08-24T20:41:07.244632Z","end":"2026-08-24T20:41:07.404703Z","steps":["trace[1918522540] 'process raft request' (duration: 64.506099ms)","trace[1918522540] 'compare' (duration: 95.213525ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:41:07.404841Z","caller":"traceutil/trace.go:171","msg":"trace[1373158302] transaction","detail":"{read_only:false; response_revision:4414; number_of_response:1; }","duration":"135.852547ms","start":"2026-08-24T20:41:07.268979Z","end":"2026-08-24T20:41:07.404832Z","steps":["trace[1373158302] 'process raft request' (duration: 135.504891ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:16.337527Z","caller":"traceutil/trace.go:171","msg":"trace[1853316801] linearizableReadLoop","detail":"{readStateIndex:4855; appliedIndex:4854; }","duration":"115.092028ms","start":"2026-08-24T20:41:16.222406Z","end":"2026-08-24T20:41:16.337498Z","steps":["trace[1853316801] 'read index received' (duration: 114.966856ms)","trace[1853316801] 'applied index is now lower than readState.Index' (duration: 123.702µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:41:16.337923Z","caller":"traceutil/trace.go:171","msg":"trace[1205235809] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"133.408122ms","start":"2026-08-24T20:41:16.204503Z","end":"2026-08-24T20:41:16.337911Z","steps":["trace[1205235809] 'process raft request' (duration: 132.81372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:16.338193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.768572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-08-24T20:41:16.338225Z","caller":"traceutil/trace.go:171","msg":"trace[506723039] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4668; }","duration":"115.848713ms","start":"2026-08-24T20:41:16.222366Z","end":"2026-08-24T20:41:16.338214Z","steps":["trace[506723039] 'agreement among raft nodes before linearized reading' (duration: 115.715871ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:16.735324Z","caller":"traceutil/trace.go:171","msg":"trace[1606555441] linearizableReadLoop","detail":"{readStateIndex:4857; appliedIndex:4856; }","duration":"228.988317ms","start":"2026-08-24T20:41:16.506316Z","end":"2026-08-24T20:41:16.735304Z","steps":["trace[1606555441] 'read index received' (duration: 228.822144ms)","trace[1606555441] 'applied index is now lower than readState.Index' (duration: 165.393µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:41:16.735442Z","caller":"traceutil/trace.go:171","msg":"trace[55559980] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"324.44254ms","start":"2026-08-24T20:41:16.410964Z","end":"2026-08-24T20:41:16.735406Z","steps":["trace[55559980] 'process raft request' (duration: 324.193784ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:16.735701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:41:16.410947Z","time spent":"324.684885ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7567,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T20:41:16.735704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.372004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-9ljsn\" ","response":"range_response_count:1 size:5138"} {"level":"info","ts":"2026-08-24T20:41:16.735779Z","caller":"traceutil/trace.go:171","msg":"trace[1196660422] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-9ljsn; range_end:; response_count:1; response_revision:4670; }","duration":"229.513567ms","start":"2026-08-24T20:41:16.506249Z","end":"2026-08-24T20:41:16.735763Z","steps":["trace[1196660422] 'agreement among raft nodes before linearized reading' (duration: 229.288513ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:22.222331Z","caller":"traceutil/trace.go:171","msg":"trace[1085832954] linearizableReadLoop","detail":"{readStateIndex:4967; appliedIndex:4966; }","duration":"184.125793ms","start":"2026-08-24T20:41:22.038178Z","end":"2026-08-24T20:41:22.222304Z","steps":["trace[1085832954] 'read index received' (duration: 183.895689ms)","trace[1085832954] 'applied index is now lower than readState.Index' (duration: 228.824µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:41:22.222341Z","caller":"traceutil/trace.go:171","msg":"trace[1952939881] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"184.997999ms","start":"2026-08-24T20:41:22.037303Z","end":"2026-08-24T20:41:22.222301Z","steps":["trace[1952939881] 'process raft request' (duration: 184.804636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:22.222725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.515571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2f75ea88-6f02-4c8f-86cc-369ae399d9c9\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-08-24T20:41:22.222778Z","caller":"traceutil/trace.go:171","msg":"trace[225077596] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2f75ea88-6f02-4c8f-86cc-369ae399d9c9; range_end:; response_count:1; response_revision:4778; }","duration":"184.606843ms","start":"2026-08-24T20:41:22.038158Z","end":"2026-08-24T20:41:22.222765Z","steps":["trace[225077596] 'agreement among raft nodes before linearized reading' (duration: 184.269177ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:22.26178Z","caller":"traceutil/trace.go:171","msg":"trace[389387535] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"220.023072ms","start":"2026-08-24T20:41:22.041722Z","end":"2026-08-24T20:41:22.261745Z","steps":["trace[389387535] 'process raft request' (duration: 219.771678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:22.278667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.221849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b6f70fc5-a55c-497b-b672-5a969985e20f\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-08-24T20:41:22.278734Z","caller":"traceutil/trace.go:171","msg":"trace[830225709] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b6f70fc5-a55c-497b-b672-5a969985e20f; range_end:; response_count:1; response_revision:4779; }","duration":"196.357522ms","start":"2026-08-24T20:41:22.082358Z","end":"2026-08-24T20:41:22.278716Z","steps":["trace[830225709] 'agreement among raft nodes before linearized reading' (duration: 196.161208ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:22.544529Z","caller":"traceutil/trace.go:171","msg":"trace[425962834] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"128.133019ms","start":"2026-08-24T20:41:22.416369Z","end":"2026-08-24T20:41:22.544502Z","steps":["trace[425962834] 'process raft request' (duration: 71.770466ms)","trace[425962834] 'compare' (duration: 56.115508ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:41:22.544907Z","caller":"traceutil/trace.go:171","msg":"trace[158639045] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"121.095413ms","start":"2026-08-24T20:41:22.423771Z","end":"2026-08-24T20:41:22.544866Z","steps":["trace[158639045] 'process raft request' (duration: 120.680346ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:22.893809Z","caller":"traceutil/trace.go:171","msg":"trace[489970046] transaction","detail":"{read_only:false; response_revision:4789; number_of_response:1; }","duration":"117.54457ms","start":"2026-08-24T20:41:22.776238Z","end":"2026-08-24T20:41:22.893782Z","steps":["trace[489970046] 'process raft request' (duration: 117.135233ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:33.987664Z","caller":"traceutil/trace.go:171","msg":"trace[2078344026] linearizableReadLoop","detail":"{readStateIndex:5140; appliedIndex:5139; }","duration":"188.894768ms","start":"2026-08-24T20:41:33.798747Z","end":"2026-08-24T20:41:33.987642Z","steps":["trace[2078344026] 'read index received' (duration: 188.675394ms)","trace[2078344026] 'applied index is now lower than readState.Index' (duration: 217.814µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:41:33.987986Z","caller":"traceutil/trace.go:171","msg":"trace[1820260750] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"257.192438ms","start":"2026-08-24T20:41:33.730784Z","end":"2026-08-24T20:41:33.987977Z","steps":["trace[1820260750] 'process raft request' (duration: 256.643109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:33.988331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.158808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"warn","ts":"2026-08-24T20:41:33.988331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.538048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:41:33.988457Z","caller":"traceutil/trace.go:171","msg":"trace[1701481318] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:4945; }","duration":"152.303961ms","start":"2026-08-24T20:41:33.836108Z","end":"2026-08-24T20:41:33.988412Z","steps":["trace[1701481318] 'agreement among raft nodes before linearized reading' (duration: 152.070887ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:33.988478Z","caller":"traceutil/trace.go:171","msg":"trace[272773813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4945; }","duration":"189.710871ms","start":"2026-08-24T20:41:33.798735Z","end":"2026-08-24T20:41:33.988446Z","steps":["trace[272773813] 'agreement among raft nodes before linearized reading' (duration: 189.456527ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:33.988386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.010317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-24T20:41:33.988535Z","caller":"traceutil/trace.go:171","msg":"trace[1190727802] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4945; }","duration":"113.19775ms","start":"2026-08-24T20:41:33.875323Z","end":"2026-08-24T20:41:33.988521Z","steps":["trace[1190727802] 'agreement among raft nodes before linearized reading' (duration: 112.925155ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:35.166655Z","caller":"traceutil/trace.go:171","msg":"trace[1119343809] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"136.913122ms","start":"2026-08-24T20:41:35.02971Z","end":"2026-08-24T20:41:35.166623Z","steps":["trace[1119343809] 'process raft request' (duration: 136.637197ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:42:12.013185Z","caller":"traceutil/trace.go:171","msg":"trace[1897954864] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"125.368949ms","start":"2026-08-24T20:42:11.887773Z","end":"2026-08-24T20:42:12.013142Z","steps":["trace[1897954864] 'process raft request' (duration: 124.984863ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:42:43.825334Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2860} {"level":"info","ts":"2026-08-24T20:42:43.957016Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2860,"took":"130.16222ms","hash":123945562,"current-db-size-bytes":54636544,"current-db-size":"55 MB","current-db-size-in-use-bytes":29704192,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-24T20:42:43.957092Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":123945562,"revision":2860,"compact-revision":-1} {"level":"info","ts":"2026-08-24T20:45:01.685333Z","caller":"traceutil/trace.go:171","msg":"trace[623069435] linearizableReadLoop","detail":"{readStateIndex:6723; appliedIndex:6722; }","duration":"111.572796ms","start":"2026-08-24T20:45:01.573735Z","end":"2026-08-24T20:45:01.685308Z","steps":["trace[623069435] 'read index received' (duration: 111.312213ms)","trace[623069435] 'applied index is now lower than readState.Index' (duration: 259.543µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:45:01.685418Z","caller":"traceutil/trace.go:171","msg":"trace[1770520663] transaction","detail":"{read_only:false; response_revision:6479; number_of_response:1; }","duration":"122.503821ms","start":"2026-08-24T20:45:01.562873Z","end":"2026-08-24T20:45:01.685377Z","steps":["trace[1770520663] 'process raft request' (duration: 122.271558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:45:01.685679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.92949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-24T20:45:01.685759Z","caller":"traceutil/trace.go:171","msg":"trace[2100466952] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6479; }","duration":"112.065721ms","start":"2026-08-24T20:45:01.573673Z","end":"2026-08-24T20:45:01.685739Z","steps":["trace[2100466952] 'agreement among raft nodes before linearized reading' (duration: 111.735597ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:45:30.604513Z","caller":"traceutil/trace.go:171","msg":"trace[857007415] transaction","detail":"{read_only:false; response_revision:6684; number_of_response:1; }","duration":"116.686651ms","start":"2026-08-24T20:45:30.487795Z","end":"2026-08-24T20:45:30.604482Z","steps":["trace[857007415] 'process raft request' (duration: 116.503559ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:45:30.633672Z","caller":"traceutil/trace.go:171","msg":"trace[621762451] transaction","detail":"{read_only:false; response_revision:6685; number_of_response:1; }","duration":"105.918041ms","start":"2026-08-24T20:45:30.527732Z","end":"2026-08-24T20:45:30.63365Z","steps":["trace[621762451] 'process raft request' (duration: 105.424306ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:45:33.093778Z","caller":"traceutil/trace.go:171","msg":"trace[1209857182] transaction","detail":"{read_only:false; response_revision:6699; number_of_response:1; }","duration":"250.306106ms","start":"2026-08-24T20:45:32.843444Z","end":"2026-08-24T20:45:33.09375Z","steps":["trace[1209857182] 'process raft request' (duration: 250.140914ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:45:33.16154Z","caller":"traceutil/trace.go:171","msg":"trace[785189418] transaction","detail":"{read_only:false; response_revision:6700; number_of_response:1; }","duration":"110.328837ms","start":"2026-08-24T20:45:33.051192Z","end":"2026-08-24T20:45:33.161521Z","steps":["trace[785189418] 'process raft request' (duration: 90.066192ms)","trace[785189418] 'compare' (duration: 19.851241ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:47:43.83205Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5535} {"level":"info","ts":"2026-08-24T20:47:43.904515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5535,"took":"71.388489ms","hash":1020797373,"current-db-size-bytes":54636544,"current-db-size":"55 MB","current-db-size-in-use-bytes":18493440,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-24T20:47:43.904568Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1020797373,"revision":5535,"compact-revision":2860}