{"level":"warn","ts":"2026-07-24T17:28:27.398836Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T17:28:27.398918Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.99:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.99:2380","--initial-cluster=instance=https://199.19.213.99:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.99:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.99: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-07-24T17:28:27.398981Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T17:28:27.398991Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.99:2380"]} {"level":"info","ts":"2026-07-24T17:28:27.399027Z","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-07-24T17:28:27.399634Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"]} {"level":"info","ts":"2026-07-24T17:28:27.399709Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.99:2380"],"listen-peer-urls":["https://199.19.213.99:2380"],"advertise-client-urls":["https://199.19.213.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.99: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-07-24T17:28:27.404379Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.284943ms"} {"level":"info","ts":"2026-07-24T17:28:27.409288Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4da37caf00b87dfe","cluster-id":"221bed930aa36d2e"} {"level":"info","ts":"2026-07-24T17:28:27.409417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T17:28:27.409446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became follower at term 0"} {"level":"info","ts":"2026-07-24T17:28:27.409456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4da37caf00b87dfe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T17:28:27.409471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became follower at term 1"} {"level":"info","ts":"2026-07-24T17:28:27.409511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=(5594452253197499902)"} {"level":"warn","ts":"2026-07-24T17:28:27.415321Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T17:28:27.416682Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T17:28:27.418002Z","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-07-24T17:28:27.420981Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4da37caf00b87dfe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T17:28:27.421163Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4da37caf00b87dfe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T17:28:27.421341Z","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-07-24T17:28:27.421461Z","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-07-24T17:28:27.421521Z","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-07-24T17:28:27.423302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=(5594452253197499902)"} {"level":"info","ts":"2026-07-24T17:28:27.424832Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"221bed930aa36d2e","local-member-id":"4da37caf00b87dfe","added-peer-id":"4da37caf00b87dfe","added-peer-peer-urls":["https://199.19.213.99:2380"]} {"level":"info","ts":"2026-07-24T17:28:27.426844Z","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-07-24T17:28:27.42714Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4da37caf00b87dfe","initial-advertise-peer-urls":["https://199.19.213.99:2380"],"listen-peer-urls":["https://199.19.213.99:2380"],"advertise-client-urls":["https://199.19.213.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T17:28:27.427175Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T17:28:27.427253Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.99:2380"} {"level":"info","ts":"2026-07-24T17:28:27.427267Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.99:2380"} {"level":"info","ts":"2026-07-24T17:28:27.810542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T17:28:27.810599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T17:28:27.810638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe received MsgPreVoteResp from 4da37caf00b87dfe at term 1"} {"level":"info","ts":"2026-07-24T17:28:27.810657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became candidate at term 2"} {"level":"info","ts":"2026-07-24T17:28:27.810668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe received MsgVoteResp from 4da37caf00b87dfe at term 2"} {"level":"info","ts":"2026-07-24T17:28:27.810685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became leader at term 2"} {"level":"info","ts":"2026-07-24T17:28:27.810698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4da37caf00b87dfe elected leader 4da37caf00b87dfe at term 2"} {"level":"info","ts":"2026-07-24T17:28:27.811545Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4da37caf00b87dfe","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.99:2379]}","request-path":"/0/members/4da37caf00b87dfe/attributes","cluster-id":"221bed930aa36d2e","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T17:28:27.811999Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T17:28:27.812012Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T17:28:27.812149Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T17:28:27.812219Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T17:28:27.812431Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T17:28:27.813158Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"221bed930aa36d2e","local-member-id":"4da37caf00b87dfe","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T17:28:27.813284Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T17:28:27.813352Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T17:28:27.815223Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-24T17:28:27.815401Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.99:2379"} {"level":"info","ts":"2026-07-24T17:28:39.02592Z","caller":"traceutil/trace.go:171","msg":"trace[759501905] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"330.59609ms","start":"2026-07-24T17:28:38.695299Z","end":"2026-07-24T17:28:39.025895Z","steps":["trace[759501905] 'process raft request' (duration: 330.445466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:28:39.026614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:28:38.695285Z","time spent":"330.719344ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:29:05.725294Z","caller":"traceutil/trace.go:171","msg":"trace[1909049576] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"171.274697ms","start":"2026-07-24T17:29:05.553989Z","end":"2026-07-24T17:29:05.725264Z","steps":["trace[1909049576] 'process raft request' (duration: 170.944518ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:29:05.730861Z","caller":"traceutil/trace.go:171","msg":"trace[2058613446] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"162.179676ms","start":"2026-07-24T17:29:05.568657Z","end":"2026-07-24T17:29:05.730837Z","steps":["trace[2058613446] 'process raft request' (duration: 161.737913ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:29:05.730989Z","caller":"traceutil/trace.go:171","msg":"trace[1569204293] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"159.071716ms","start":"2026-07-24T17:29:05.571911Z","end":"2026-07-24T17:29:05.730983Z","steps":["trace[1569204293] 'process raft request' (duration: 158.8621ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:29:11.908324Z","caller":"traceutil/trace.go:171","msg":"trace[709298788] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"131.867699ms","start":"2026-07-24T17:29:11.776427Z","end":"2026-07-24T17:29:11.908294Z","steps":["trace[709298788] 'process raft request' (duration: 131.229931ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:29:12.130678Z","caller":"traceutil/trace.go:171","msg":"trace[562190382] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"160.040813ms","start":"2026-07-24T17:29:11.970605Z","end":"2026-07-24T17:29:12.130646Z","steps":["trace[562190382] 'process raft request' (duration: 104.660214ms)","trace[562190382] 'compare' (duration: 55.030859ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:29:13.545908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.66017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:29:13.546038Z","caller":"traceutil/trace.go:171","msg":"trace[1879049462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"574.848337ms","start":"2026-07-24T17:29:12.971166Z","end":"2026-07-24T17:29:13.546015Z","steps":["trace[1879049462] 'range keys from in-memory index tree' (duration: 574.561298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:29:13.546111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:29:12.971143Z","time spent":"574.93907ms","remote":"127.0.0.1:52724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T17:29:13.693865Z","caller":"traceutil/trace.go:171","msg":"trace[725057783] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"483.698292ms","start":"2026-07-24T17:29:13.210078Z","end":"2026-07-24T17:29:13.693776Z","steps":["trace[725057783] 'read index received' (duration: 479.258255ms)","trace[725057783] 'applied index is now lower than readState.Index' (duration: 4.438087ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:29:13.694359Z","caller":"traceutil/trace.go:171","msg":"trace[853450491] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"578.123561ms","start":"2026-07-24T17:29:13.116188Z","end":"2026-07-24T17:29:13.694312Z","steps":["trace[853450491] 'process raft request' (duration: 574.207667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:29:13.694703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.601589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-24T17:29:13.69475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:29:13.116149Z","time spent":"578.411719ms","remote":"127.0.0.1:52808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:29:13.694826Z","caller":"traceutil/trace.go:171","msg":"trace[1139292391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"484.751474ms","start":"2026-07-24T17:29:13.21002Z","end":"2026-07-24T17:29:13.694772Z","steps":["trace[1139292391] 'agreement among raft nodes before linearized reading' (duration: 484.171387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:29:13.694881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:29:13.209998Z","time spent":"484.870007ms","remote":"127.0.0.1:52996","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":"warn","ts":"2026-07-24T17:29:13.70882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.999383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-24T17:29:13.708885Z","caller":"traceutil/trace.go:171","msg":"trace[132506606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:636; }","duration":"249.113396ms","start":"2026-07-24T17:29:13.459756Z","end":"2026-07-24T17:29:13.708869Z","steps":["trace[132506606] 'agreement among raft nodes before linearized reading' (duration: 248.923221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:29:13.708925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.372412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:29:13.709029Z","caller":"traceutil/trace.go:171","msg":"trace[1529840412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"161.511906ms","start":"2026-07-24T17:29:13.547487Z","end":"2026-07-24T17:29:13.708999Z","steps":["trace[1529840412] 'agreement among raft nodes before linearized reading' (duration: 161.332671ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:29:29.261479Z","caller":"traceutil/trace.go:171","msg":"trace[567666823] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"155.757411ms","start":"2026-07-24T17:29:29.105691Z","end":"2026-07-24T17:29:29.261448Z","steps":["trace[567666823] 'process raft request' (duration: 155.526253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:29:56.224264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.836937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:29:56.225173Z","caller":"traceutil/trace.go:171","msg":"trace[1665430306] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1223; }","duration":"151.984421ms","start":"2026-07-24T17:29:56.073127Z","end":"2026-07-24T17:29:56.225111Z","steps":["trace[1665430306] 'read index received' (duration: 38.692µs)","trace[1665430306] 'applied index is now lower than readState.Index' (duration: 151.834626ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:29:56.225196Z","caller":"traceutil/trace.go:171","msg":"trace[1345426752] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"209.258667ms","start":"2026-07-24T17:29:56.01586Z","end":"2026-07-24T17:29:56.225119Z","steps":["trace[1345426752] 'process raft request' (duration: 48.983478ms)","trace[1345426752] 'compare' (duration: 158.642162ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:29:56.225358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.175346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:29:56.225482Z","caller":"traceutil/trace.go:171","msg":"trace[1286684331] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1191; }","duration":"152.343821ms","start":"2026-07-24T17:29:56.073087Z","end":"2026-07-24T17:29:56.225431Z","steps":["trace[1286684331] 'agreement among raft nodes before linearized reading' (duration: 152.055773ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:30:40.430219Z","caller":"traceutil/trace.go:171","msg":"trace[570538785] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"154.951205ms","start":"2026-07-24T17:30:40.275242Z","end":"2026-07-24T17:30:40.430193Z","steps":["trace[570538785] 'process raft request' (duration: 154.78362ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:30:40.859128Z","caller":"traceutil/trace.go:171","msg":"trace[577584980] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"101.377785ms","start":"2026-07-24T17:30:40.757723Z","end":"2026-07-24T17:30:40.859101Z","steps":["trace[577584980] 'process raft request' (duration: 101.21434ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:31:25.231878Z","caller":"traceutil/trace.go:171","msg":"trace[494415963] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"115.164424ms","start":"2026-07-24T17:31:25.116684Z","end":"2026-07-24T17:31:25.231848Z","steps":["trace[494415963] 'process raft request' (duration: 115.013909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:32:20.08027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.420247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:32:20.080405Z","caller":"traceutil/trace.go:171","msg":"trace[1078734816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2608; }","duration":"109.595582ms","start":"2026-07-24T17:32:19.970782Z","end":"2026-07-24T17:32:20.080378Z","steps":["trace[1078734816] 'range keys from in-memory index tree' (duration: 109.339214ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:32:54.428094Z","caller":"traceutil/trace.go:171","msg":"trace[1497688572] transaction","detail":"{read_only:false; response_revision:2815; number_of_response:1; }","duration":"102.672149ms","start":"2026-07-24T17:32:54.325382Z","end":"2026-07-24T17:32:54.428054Z","steps":["trace[1497688572] 'process raft request' (duration: 102.407021ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:35:24.82153Z","caller":"traceutil/trace.go:171","msg":"trace[1863915644] transaction","detail":"{read_only:false; response_revision:3810; number_of_response:1; }","duration":"287.353535ms","start":"2026-07-24T17:35:24.534149Z","end":"2026-07-24T17:35:24.821503Z","steps":["trace[1863915644] 'process raft request' (duration: 287.166729ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:36:50.151455Z","caller":"traceutil/trace.go:171","msg":"trace[914930686] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"128.404103ms","start":"2026-07-24T17:36:50.023021Z","end":"2026-07-24T17:36:50.151425Z","steps":["trace[914930686] 'process raft request' (duration: 91.740357ms)","trace[914930686] 'compare' (duration: 36.540992ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:36:50.171443Z","caller":"traceutil/trace.go:171","msg":"trace[537115486] transaction","detail":"{read_only:false; response_revision:4869; number_of_response:1; }","duration":"127.561408ms","start":"2026-07-24T17:36:50.043853Z","end":"2026-07-24T17:36:50.171415Z","steps":["trace[537115486] 'process raft request' (duration: 127.381962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:54.743836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.08257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ec7e73c1-aa44-4cc8-a18c-c227367fdd22\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-07-24T17:36:54.743921Z","caller":"traceutil/trace.go:171","msg":"trace[557076882] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ec7e73c1-aa44-4cc8-a18c-c227367fdd22; range_end:; response_count:1; response_revision:5027; }","duration":"163.244395ms","start":"2026-07-24T17:36:54.580662Z","end":"2026-07-24T17:36:54.743906Z","steps":["trace[557076882] 'range keys from in-memory index tree' (duration: 162.902376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:06.656314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.713263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:37:06.656537Z","caller":"traceutil/trace.go:171","msg":"trace[1807695128] linearizableReadLoop","detail":"{readStateIndex:5453; appliedIndex:5452; }","duration":"156.387637ms","start":"2026-07-24T17:37:06.500121Z","end":"2026-07-24T17:37:06.656509Z","steps":["trace[1807695128] 'read index received' (duration: 17.424727ms)","trace[1807695128] 'applied index is now lower than readState.Index' (duration: 138.95978ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:37:06.656533Z","caller":"traceutil/trace.go:171","msg":"trace[1792024919] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"184.637108ms","start":"2026-07-24T17:37:06.471849Z","end":"2026-07-24T17:37:06.656486Z","steps":["trace[1792024919] 'process raft request' (duration: 45.679458ms)","trace[1792024919] 'compare' (duration: 138.23152ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:06.69757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.44156ms","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-07-24T17:37:06.69764Z","caller":"traceutil/trace.go:171","msg":"trace[220175519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5257; }","duration":"197.565444ms","start":"2026-07-24T17:37:06.500057Z","end":"2026-07-24T17:37:06.697622Z","steps":["trace[220175519] 'agreement among raft nodes before linearized reading' (duration: 156.544892ms)","trace[220175519] 'range keys from in-memory index tree' (duration: 40.846537ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:37:07.083813Z","caller":"traceutil/trace.go:171","msg":"trace[800637636] linearizableReadLoop","detail":"{readStateIndex:5456; appliedIndex:5455; }","duration":"156.666665ms","start":"2026-07-24T17:37:06.927117Z","end":"2026-07-24T17:37:07.083784Z","steps":["trace[800637636] 'read index received' (duration: 156.385647ms)","trace[800637636] 'applied index is now lower than readState.Index' (duration: 279.748µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:37:07.0844Z","caller":"traceutil/trace.go:171","msg":"trace[918313516] transaction","detail":"{read_only:false; response_revision:5259; number_of_response:1; }","duration":"216.296189ms","start":"2026-07-24T17:37:06.86807Z","end":"2026-07-24T17:37:07.084366Z","steps":["trace[918313516] 'process raft request' (duration: 215.509955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:07.091683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.099251ms","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-07-24T17:37:07.091786Z","caller":"traceutil/trace.go:171","msg":"trace[1336424499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5259; }","duration":"164.681928ms","start":"2026-07-24T17:37:06.927077Z","end":"2026-07-24T17:37:07.091759Z","steps":["trace[1336424499] 'agreement among raft nodes before linearized reading' (duration: 157.238672ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:14.914531Z","caller":"traceutil/trace.go:171","msg":"trace[432156505] transaction","detail":"{read_only:false; response_revision:5439; number_of_response:1; }","duration":"162.924716ms","start":"2026-07-24T17:37:14.751567Z","end":"2026-07-24T17:37:14.914492Z","steps":["trace[432156505] 'process raft request' (duration: 162.634188ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:15.122258Z","caller":"traceutil/trace.go:171","msg":"trace[1523944549] linearizableReadLoop","detail":"{readStateIndex:5641; appliedIndex:5640; }","duration":"154.81305ms","start":"2026-07-24T17:37:14.967418Z","end":"2026-07-24T17:37:15.122231Z","steps":["trace[1523944549] 'read index received' (duration: 154.604694ms)","trace[1523944549] 'applied index is now lower than readState.Index' (duration: 207.146µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:37:15.122363Z","caller":"traceutil/trace.go:171","msg":"trace[58515451] transaction","detail":"{read_only:false; response_revision:5440; number_of_response:1; }","duration":"158.036325ms","start":"2026-07-24T17:37:14.964295Z","end":"2026-07-24T17:37:15.122331Z","steps":["trace[58515451] 'process raft request' (duration: 157.754926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:15.122514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.939019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"warn","ts":"2026-07-24T17:37:15.122492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.049426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:37:15.122558Z","caller":"traceutil/trace.go:171","msg":"trace[320870908] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5440; }","duration":"149.999281ms","start":"2026-07-24T17:37:14.972547Z","end":"2026-07-24T17:37:15.122546Z","steps":["trace[320870908] 'agreement among raft nodes before linearized reading' (duration: 149.846976ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:15.122591Z","caller":"traceutil/trace.go:171","msg":"trace[608808330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5440; }","duration":"155.213961ms","start":"2026-07-24T17:37:14.967355Z","end":"2026-07-24T17:37:15.122569Z","steps":["trace[608808330] 'agreement among raft nodes before linearized reading' (duration: 155.018796ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:15.309764Z","caller":"traceutil/trace.go:171","msg":"trace[711904448] transaction","detail":"{read_only:false; response_revision:5442; number_of_response:1; }","duration":"168.441336ms","start":"2026-07-24T17:37:15.141284Z","end":"2026-07-24T17:37:15.309725Z","steps":["trace[711904448] 'process raft request' (duration: 168.049615ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:15.309478Z","caller":"traceutil/trace.go:171","msg":"trace[521200131] transaction","detail":"{read_only:false; response_revision:5441; number_of_response:1; }","duration":"173.005479ms","start":"2026-07-24T17:37:15.136396Z","end":"2026-07-24T17:37:15.309401Z","steps":["trace[521200131] 'process raft request' (duration: 110.81362ms)","trace[521200131] 'compare' (duration: 61.583951ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:37:15.441083Z","caller":"traceutil/trace.go:171","msg":"trace[1697616243] transaction","detail":"{read_only:false; response_revision:5443; number_of_response:1; }","duration":"124.946952ms","start":"2026-07-24T17:37:15.316095Z","end":"2026-07-24T17:37:15.441042Z","steps":["trace[1697616243] 'process raft request' (duration: 99.289506ms)","trace[1697616243] 'compare' (duration: 25.405249ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:37:15.441248Z","caller":"traceutil/trace.go:171","msg":"trace[2101971637] transaction","detail":"{read_only:false; response_revision:5446; number_of_response:1; }","duration":"124.018856ms","start":"2026-07-24T17:37:15.317206Z","end":"2026-07-24T17:37:15.441225Z","steps":["trace[2101971637] 'process raft request' (duration: 123.900122ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:15.441643Z","caller":"traceutil/trace.go:171","msg":"trace[1149087861] transaction","detail":"{read_only:false; response_revision:5448; number_of_response:1; }","duration":"121.518883ms","start":"2026-07-24T17:37:15.3201Z","end":"2026-07-24T17:37:15.441618Z","steps":["trace[1149087861] 'process raft request' (duration: 121.0777ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:15.441741Z","caller":"traceutil/trace.go:171","msg":"trace[89374398] transaction","detail":"{read_only:false; response_revision:5449; number_of_response:1; }","duration":"116.858077ms","start":"2026-07-24T17:37:15.324873Z","end":"2026-07-24T17:37:15.441731Z","steps":["trace[89374398] 'process raft request' (duration: 116.503547ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:15.441192Z","caller":"traceutil/trace.go:171","msg":"trace[468432079] transaction","detail":"{read_only:false; response_revision:5445; number_of_response:1; }","duration":"124.056847ms","start":"2026-07-24T17:37:15.31711Z","end":"2026-07-24T17:37:15.441167Z","steps":["trace[468432079] 'process raft request' (duration: 123.950454ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:15.441176Z","caller":"traceutil/trace.go:171","msg":"trace[1278139580] transaction","detail":"{read_only:false; response_revision:5444; number_of_response:1; }","duration":"124.86836ms","start":"2026-07-24T17:37:15.316279Z","end":"2026-07-24T17:37:15.441147Z","steps":["trace[1278139580] 'process raft request' (duration: 124.675165ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:15.441239Z","caller":"traceutil/trace.go:171","msg":"trace[795479226] transaction","detail":"{read_only:false; response_revision:5447; number_of_response:1; }","duration":"123.078868ms","start":"2026-07-24T17:37:15.318149Z","end":"2026-07-24T17:37:15.441228Z","steps":["trace[795479226] 'process raft request' (duration: 122.992595ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:38:28.212703Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3002} {"level":"info","ts":"2026-07-24T17:38:28.297317Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3002,"took":"82.925511ms","hash":2972801456,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":31784960,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-24T17:38:28.297397Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2972801456,"revision":3002,"compact-revision":-1} {"level":"info","ts":"2026-07-24T17:43:17.340806Z","caller":"traceutil/trace.go:171","msg":"trace[1736427521] transaction","detail":"{read_only:false; response_revision:8240; number_of_response:1; }","duration":"323.990513ms","start":"2026-07-24T17:43:17.016795Z","end":"2026-07-24T17:43:17.340786Z","steps":["trace[1736427521] 'process raft request' (duration: 323.843029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:43:17.340936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:43:17.01678Z","time spent":"324.094057ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:43:17.469475Z","caller":"traceutil/trace.go:171","msg":"trace[1974063114] linearizableReadLoop","detail":"{readStateIndex:8529; appliedIndex:8528; }","duration":"128.681811ms","start":"2026-07-24T17:43:17.340762Z","end":"2026-07-24T17:43:17.469443Z","steps":["trace[1974063114] 'read index received' (duration: 128.170036ms)","trace[1974063114] 'applied index is now lower than readState.Index' (duration: 510.455µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:43:17.46958Z","caller":"traceutil/trace.go:171","msg":"trace[427638472] transaction","detail":"{read_only:false; response_revision:8241; number_of_response:1; }","duration":"186.25808ms","start":"2026-07-24T17:43:17.283291Z","end":"2026-07-24T17:43:17.469549Z","steps":["trace[427638472] 'process raft request' (duration: 185.631512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:43:17.469723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.159816ms","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-07-24T17:43:17.469784Z","caller":"traceutil/trace.go:171","msg":"trace[1601057737] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8241; }","duration":"197.264419ms","start":"2026-07-24T17:43:17.272501Z","end":"2026-07-24T17:43:17.469766Z","steps":["trace[1601057737] 'agreement among raft nodes before linearized reading' (duration: 197.076424ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:43:28.218806Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6108} {"level":"info","ts":"2026-07-24T17:43:28.321245Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6108,"took":"100.277658ms","hash":4132506172,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":23818240,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-24T17:43:28.321357Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4132506172,"revision":6108,"compact-revision":3002} {"level":"info","ts":"2026-07-24T17:43:28.904137Z","caller":"traceutil/trace.go:171","msg":"trace[161068566] transaction","detail":"{read_only:false; response_revision:8309; number_of_response:1; }","duration":"127.685121ms","start":"2026-07-24T17:43:28.776427Z","end":"2026-07-24T17:43:28.904112Z","steps":["trace[161068566] 'process raft request' (duration: 127.473695ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:43:29.14053Z","caller":"traceutil/trace.go:171","msg":"trace[1856732089] transaction","detail":"{read_only:false; response_revision:8310; number_of_response:1; }","duration":"356.822936ms","start":"2026-07-24T17:43:28.783686Z","end":"2026-07-24T17:43:29.140509Z","steps":["trace[1856732089] 'process raft request' (duration: 356.657392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:43:29.140661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:43:28.783677Z","time spent":"356.91301ms","remote":"127.0.0.1:52996","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-07-24T17:43:29.140895Z","caller":"traceutil/trace.go:171","msg":"trace[158509101] transaction","detail":"{read_only:false; response_revision:8311; number_of_response:1; }","duration":"329.852504ms","start":"2026-07-24T17:43:28.810967Z","end":"2026-07-24T17:43:29.14082Z","steps":["trace[158509101] 'process raft request' (duration: 329.497733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:43:29.141049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:43:28.810949Z","time spent":"330.02085ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:43:29.141064Z","caller":"traceutil/trace.go:171","msg":"trace[232262794] linearizableReadLoop","detail":"{readStateIndex:8604; appliedIndex:8601; }","duration":"169.281018ms","start":"2026-07-24T17:43:28.971753Z","end":"2026-07-24T17:43:29.141034Z","steps":["trace[232262794] 'read index received' (duration: 168.574617ms)","trace[232262794] 'applied index is now lower than readState.Index' (duration: 703.781µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:43:29.141122Z","caller":"traceutil/trace.go:171","msg":"trace[94329168] transaction","detail":"{read_only:false; response_revision:8312; number_of_response:1; }","duration":"289.635959ms","start":"2026-07-24T17:43:28.851446Z","end":"2026-07-24T17:43:29.141082Z","steps":["trace[94329168] 'process raft request' (duration: 289.290399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:43:29.14116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.418513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:43:29.141289Z","caller":"traceutil/trace.go:171","msg":"trace[62699257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8312; }","duration":"169.578567ms","start":"2026-07-24T17:43:28.971691Z","end":"2026-07-24T17:43:29.14127Z","steps":["trace[62699257] 'agreement among raft nodes before linearized reading' (duration: 169.445953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:43:29.887255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.752842ms","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-07-24T17:43:29.887374Z","caller":"traceutil/trace.go:171","msg":"trace[161920062] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8315; }","duration":"139.922917ms","start":"2026-07-24T17:43:29.747435Z","end":"2026-07-24T17:43:29.887358Z","steps":["trace[161920062] 'range keys from in-memory index tree' (duration: 139.602239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:43:29.887483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.219601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-24T17:43:29.887545Z","caller":"traceutil/trace.go:171","msg":"trace[1047542466] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8315; }","duration":"144.327624ms","start":"2026-07-24T17:43:29.743203Z","end":"2026-07-24T17:43:29.887531Z","steps":["trace[1047542466] 'range keys from in-memory index tree' (duration: 144.061717ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:43:57.86726Z","caller":"traceutil/trace.go:171","msg":"trace[298830318] linearizableReadLoop","detail":"{readStateIndex:8816; appliedIndex:8815; }","duration":"171.002956ms","start":"2026-07-24T17:43:57.696238Z","end":"2026-07-24T17:43:57.867241Z","steps":["trace[298830318] 'read index received' (duration: 170.80532ms)","trace[298830318] 'applied index is now lower than readState.Index' (duration: 197.016µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:43:57.867447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.187321ms","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-07-24T17:43:57.867477Z","caller":"traceutil/trace.go:171","msg":"trace[1516192064] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8516; }","duration":"171.259373ms","start":"2026-07-24T17:43:57.696208Z","end":"2026-07-24T17:43:57.867467Z","steps":["trace[1516192064] 'agreement among raft nodes before linearized reading' (duration: 171.116319ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:43:57.867768Z","caller":"traceutil/trace.go:171","msg":"trace[1549421442] transaction","detail":"{read_only:false; response_revision:8516; number_of_response:1; }","duration":"224.443234ms","start":"2026-07-24T17:43:57.643316Z","end":"2026-07-24T17:43:57.867759Z","steps":["trace[1549421442] 'process raft request' (duration: 223.804895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:43:58.079192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.591742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-666ddf7d6f-4tg8r.18c54abbd87ceb7f\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-07-24T17:43:58.079283Z","caller":"traceutil/trace.go:171","msg":"trace[421700664] range","detail":"{range_begin:/registry/events/openstack/barbican-api-666ddf7d6f-4tg8r.18c54abbd87ceb7f; range_end:; response_count:1; response_revision:8517; }","duration":"103.710626ms","start":"2026-07-24T17:43:57.975551Z","end":"2026-07-24T17:43:58.079261Z","steps":["trace[421700664] 'range keys from in-memory index tree' (duration: 103.443248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:43:58.079775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.76297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:43:58.079994Z","caller":"traceutil/trace.go:171","msg":"trace[658942471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8517; }","duration":"111.006617ms","start":"2026-07-24T17:43:57.968967Z","end":"2026-07-24T17:43:58.079973Z","steps":["trace[658942471] 'range keys from in-memory index tree' (duration: 110.693709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:44:09.91833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.988328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T17:44:09.918473Z","caller":"traceutil/trace.go:171","msg":"trace[655537083] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8620; }","duration":"122.437768ms","start":"2026-07-24T17:44:09.796012Z","end":"2026-07-24T17:44:09.91845Z","steps":["trace[655537083] 'compare' (duration: 109.864514ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:44:09.944665Z","caller":"traceutil/trace.go:171","msg":"trace[967750214] transaction","detail":"{read_only:false; response_revision:8621; number_of_response:1; }","duration":"145.832347ms","start":"2026-07-24T17:44:09.798489Z","end":"2026-07-24T17:44:09.944322Z","steps":["trace[967750214] 'process raft request' (duration: 145.661952ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:44:10.010635Z","caller":"traceutil/trace.go:171","msg":"trace[1815282158] transaction","detail":"{read_only:false; response_revision:8622; number_of_response:1; }","duration":"104.809128ms","start":"2026-07-24T17:44:09.905793Z","end":"2026-07-24T17:44:10.010602Z","steps":["trace[1815282158] 'process raft request' (duration: 104.445307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:44:10.643299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.866512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:44:10.643443Z","caller":"traceutil/trace.go:171","msg":"trace[1864452889] transaction","detail":"{read_only:false; response_revision:8629; number_of_response:1; }","duration":"220.441038ms","start":"2026-07-24T17:44:10.422989Z","end":"2026-07-24T17:44:10.64343Z","steps":["trace[1864452889] 'process raft request' (duration: 220.396687ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:44:10.64369Z","caller":"traceutil/trace.go:171","msg":"trace[1750208883] transaction","detail":"{read_only:false; response_revision:8628; number_of_response:1; }","duration":"233.181228ms","start":"2026-07-24T17:44:10.4105Z","end":"2026-07-24T17:44:10.643681Z","steps":["trace[1750208883] 'process raft request' (duration: 110.862373ms)","trace[1750208883] 'compare' (duration: 121.529132ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:44:10.643776Z","caller":"traceutil/trace.go:171","msg":"trace[1953483424] linearizableReadLoop","detail":"{readStateIndex:8932; appliedIndex:8931; }","duration":"222.622162ms","start":"2026-07-24T17:44:10.421148Z","end":"2026-07-24T17:44:10.64377Z","steps":["trace[1953483424] 'read index received' (duration: 100.537203ms)","trace[1953483424] 'applied index is now lower than readState.Index' (duration: 122.084209ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:44:10.643994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.845738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-nsdnd\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-07-24T17:44:10.644021Z","caller":"traceutil/trace.go:171","msg":"trace[2107571804] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-nsdnd; range_end:; response_count:1; response_revision:8629; }","duration":"222.874639ms","start":"2026-07-24T17:44:10.421139Z","end":"2026-07-24T17:44:10.644014Z","steps":["trace[2107571804] 'agreement among raft nodes before linearized reading' (duration: 222.775536ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:44:10.852092Z","caller":"traceutil/trace.go:171","msg":"trace[1073459609] linearizableReadLoop","detail":"{readStateIndex:8934; appliedIndex:8933; }","duration":"124.375714ms","start":"2026-07-24T17:44:10.727679Z","end":"2026-07-24T17:44:10.852055Z","steps":["trace[1073459609] 'read index received' (duration: 124.193489ms)","trace[1073459609] 'applied index is now lower than readState.Index' (duration: 180.945µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:44:10.852415Z","caller":"traceutil/trace.go:171","msg":"trace[549936468] transaction","detail":"{read_only:false; response_revision:8630; number_of_response:1; }","duration":"201.616782ms","start":"2026-07-24T17:44:10.650785Z","end":"2026-07-24T17:44:10.852402Z","steps":["trace[549936468] 'process raft request' (duration: 201.085927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:44:10.852864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.167576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vbwq7\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-07-24T17:44:10.852963Z","caller":"traceutil/trace.go:171","msg":"trace[634165838] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vbwq7; range_end:; response_count:1; response_revision:8630; }","duration":"125.311961ms","start":"2026-07-24T17:44:10.727631Z","end":"2026-07-24T17:44:10.852943Z","steps":["trace[634165838] 'agreement among raft nodes before linearized reading' (duration: 125.037563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:44:10.911381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.483981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-24T17:44:10.911452Z","caller":"traceutil/trace.go:171","msg":"trace[639776554] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8631; }","duration":"179.595574ms","start":"2026-07-24T17:44:10.731841Z","end":"2026-07-24T17:44:10.911437Z","steps":["trace[639776554] 'agreement among raft nodes before linearized reading' (duration: 179.368457ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:44:10.91163Z","caller":"traceutil/trace.go:171","msg":"trace[1166598840] transaction","detail":"{read_only:false; response_revision:8631; number_of_response:1; }","duration":"131.391167ms","start":"2026-07-24T17:44:10.780209Z","end":"2026-07-24T17:44:10.9116Z","steps":["trace[1166598840] 'process raft request' (duration: 130.812831ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:44:11.551461Z","caller":"traceutil/trace.go:171","msg":"trace[449661295] transaction","detail":"{read_only:false; response_revision:8636; number_of_response:1; }","duration":"177.022909ms","start":"2026-07-24T17:44:11.374412Z","end":"2026-07-24T17:44:11.551435Z","steps":["trace[449661295] 'process raft request' (duration: 176.860405ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:44:46.361594Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002444.wal"} {"level":"info","ts":"2026-07-24T17:45:13.173419Z","caller":"traceutil/trace.go:171","msg":"trace[1641803738] transaction","detail":"{read_only:false; response_revision:9292; number_of_response:1; }","duration":"116.726963ms","start":"2026-07-24T17:45:13.056662Z","end":"2026-07-24T17:45:13.173389Z","steps":["trace[1641803738] 'process raft request' (duration: 116.460395ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:46:05.728458Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4da37caf00b87dfe","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T17:46:05.735823Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-24T17:46:05.735924Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-24T17:48:28.227308Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8308} {"level":"info","ts":"2026-07-24T17:48:28.311465Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8308,"took":"81.7225ms","hash":1935693530,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":26378240,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-24T17:48:28.311538Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1935693530,"revision":8308,"compact-revision":6108} {"level":"warn","ts":"2026-07-24T17:48:47.746234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.840144ms","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-07-24T17:48:47.746368Z","caller":"traceutil/trace.go:171","msg":"trace[1235084612] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11098; }","duration":"133.024189ms","start":"2026-07-24T17:48:47.613321Z","end":"2026-07-24T17:48:47.746345Z","steps":["trace[1235084612] 'range keys from in-memory index tree' (duration: 132.655468ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:48:47.746421Z","caller":"traceutil/trace.go:171","msg":"trace[1911718] transaction","detail":"{read_only:false; response_revision:11099; number_of_response:1; }","duration":"132.155853ms","start":"2026-07-24T17:48:47.614235Z","end":"2026-07-24T17:48:47.74639Z","steps":["trace[1911718] 'process raft request' (duration: 128.52958ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:49:06.105948Z","caller":"traceutil/trace.go:171","msg":"trace[777557563] linearizableReadLoop","detail":"{readStateIndex:11579; appliedIndex:11578; }","duration":"136.89891ms","start":"2026-07-24T17:49:05.969021Z","end":"2026-07-24T17:49:06.10592Z","steps":["trace[777557563] 'read index received' (duration: 71.630861ms)","trace[777557563] 'applied index is now lower than readState.Index' (duration: 65.266959ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:49:06.106021Z","caller":"traceutil/trace.go:171","msg":"trace[1287538118] transaction","detail":"{read_only:false; response_revision:11203; number_of_response:1; }","duration":"198.35659ms","start":"2026-07-24T17:49:05.907629Z","end":"2026-07-24T17:49:06.105986Z","steps":["trace[1287538118] 'process raft request' (duration: 133.08073ms)","trace[1287538118] 'compare' (duration: 65.071684ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:49:06.106121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.089375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:49:06.106209Z","caller":"traceutil/trace.go:171","msg":"trace[263981621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11203; }","duration":"137.21881ms","start":"2026-07-24T17:49:05.968968Z","end":"2026-07-24T17:49:06.106187Z","steps":["trace[263981621] 'agreement among raft nodes before linearized reading' (duration: 137.081696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:49:06.106345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.438573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-24T17:49:06.106421Z","caller":"traceutil/trace.go:171","msg":"trace[106382188] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:11203; }","duration":"124.521176ms","start":"2026-07-24T17:49:05.981878Z","end":"2026-07-24T17:49:06.106399Z","steps":["trace[106382188] 'agreement among raft nodes before linearized reading' (duration: 124.224867ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:49:06.357359Z","caller":"traceutil/trace.go:171","msg":"trace[1178123582] transaction","detail":"{read_only:false; response_revision:11207; number_of_response:1; }","duration":"181.154266ms","start":"2026-07-24T17:49:06.176147Z","end":"2026-07-24T17:49:06.357302Z","steps":["trace[1178123582] 'process raft request' (duration: 180.742374ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:49:06.357289Z","caller":"traceutil/trace.go:171","msg":"trace[560501517] linearizableReadLoop","detail":"{readStateIndex:11583; appliedIndex:11582; }","duration":"154.461412ms","start":"2026-07-24T17:49:06.202771Z","end":"2026-07-24T17:49:06.357233Z","steps":["trace[560501517] 'read index received' (duration: 153.996879ms)","trace[560501517] 'applied index is now lower than readState.Index' (duration: 463.533µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:49:06.357712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.895905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-07-24T17:49:06.357796Z","caller":"traceutil/trace.go:171","msg":"trace[2105175577] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:11207; }","duration":"155.024869ms","start":"2026-07-24T17:49:06.202731Z","end":"2026-07-24T17:49:06.357756Z","steps":["trace[2105175577] 'agreement among raft nodes before linearized reading' (duration: 154.7132ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:49:06.607223Z","caller":"traceutil/trace.go:171","msg":"trace[1623266913] transaction","detail":"{read_only:false; response_revision:11209; number_of_response:1; }","duration":"120.942043ms","start":"2026-07-24T17:49:06.486248Z","end":"2026-07-24T17:49:06.60719Z","steps":["trace[1623266913] 'process raft request' (duration: 91.400268ms)","trace[1623266913] 'compare' (duration: 29.245577ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:52:15.213992Z","caller":"traceutil/trace.go:171","msg":"trace[156391517] transaction","detail":"{read_only:false; response_revision:12440; number_of_response:1; }","duration":"323.736811ms","start":"2026-07-24T17:52:14.890213Z","end":"2026-07-24T17:52:15.21395Z","steps":["trace[156391517] 'process raft request' (duration: 323.290947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:15.214245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:14.890172Z","time spent":"323.941487ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:52:16.181323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316456,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:16.682213Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316456,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:17.185336Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316456,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:17.685687Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316456,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:17.968432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999868182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-24T17:52:17.968538Z","caller":"traceutil/trace.go:171","msg":"trace[1737259602] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000032617s","start":"2026-07-24T17:52:15.968483Z","end":"2026-07-24T17:52:17.968516Z","steps":["trace[1737259602] 'agreement among raft nodes before linearized reading' (duration: 1.999893693s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:17.968594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:15.968465Z","time spent":"2.000118089s","remote":"127.0.0.1:52724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T17:52:18.186316Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316456,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:18.68745Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316456,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:18.791189Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.130580668s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T17:52:18.803276Z","caller":"traceutil/trace.go:171","msg":"trace[1621761655] transaction","detail":"{read_only:false; response_revision:12441; number_of_response:1; }","duration":"3.142727426s","start":"2026-07-24T17:52:15.660512Z","end":"2026-07-24T17:52:18.803239Z","steps":["trace[1621761655] 'process raft request' (duration: 3.142546671s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:18.803483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:15.66049Z","time spent":"3.14288805s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:52:21.126969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.259925058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-24T17:52:21.127089Z","caller":"traceutil/trace.go:171","msg":"trace[623292351] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"5.260128963s","start":"2026-07-24T17:52:15.866934Z","end":"2026-07-24T17:52:21.127063Z","steps":["trace[623292351] 'agreement among raft nodes before linearized reading' (duration: 5.259967778s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.127144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:15.866908Z","time spent":"5.260226226s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/07/24 17:52:21 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-24T17:52:21.205245Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.413825255s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T17:52:21.2566Z","caller":"traceutil/trace.go:171","msg":"trace[159105605] transaction","detail":"{read_only:false; response_revision:12442; number_of_response:1; }","duration":"4.947469113s","start":"2026-07-24T17:52:16.30909Z","end":"2026-07-24T17:52:21.256559Z","steps":["trace[159105605] 'process raft request' (duration: 4.896360409s)","trace[159105605] 'compare' (duration: 50.480575ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:52:21.256653Z","caller":"traceutil/trace.go:171","msg":"trace[417939297] linearizableReadLoop","detail":"{readStateIndex:12859; appliedIndex:12857; }","duration":"5.575465574s","start":"2026-07-24T17:52:15.681133Z","end":"2026-07-24T17:52:21.256598Z","steps":["trace[417939297] 'read index received' (duration: 3.121813437s)","trace[417939297] 'applied index is now lower than readState.Index' (duration: 2.453651107s)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:52:21.256762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:16.309076Z","time spent":"4.947595516s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:52:21.260672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.579507439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T17:52:21.260791Z","caller":"traceutil/trace.go:171","msg":"trace[815171897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12442; }","duration":"5.579695225s","start":"2026-07-24T17:52:15.681068Z","end":"2026-07-24T17:52:21.260763Z","steps":["trace[815171897] 'agreement among raft nodes before linearized reading' (duration: 5.575620769s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.260857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:15.681037Z","time spent":"5.579797638s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-24T17:52:21.261036Z","caller":"traceutil/trace.go:171","msg":"trace[83584259] transaction","detail":"{read_only:false; response_revision:12443; number_of_response:1; }","duration":"114.285583ms","start":"2026-07-24T17:52:21.146731Z","end":"2026-07-24T17:52:21.261017Z","steps":["trace[83584259] 'process raft request' (duration: 113.601084ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:21.261447Z","caller":"traceutil/trace.go:171","msg":"trace[767683622] transaction","detail":"{read_only:false; response_revision:12444; number_of_response:1; }","duration":"114.689994ms","start":"2026-07-24T17:52:21.146746Z","end":"2026-07-24T17:52:21.261436Z","steps":["trace[767683622] 'process raft request' (duration: 114.182519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.261669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.33784138s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/cinder-bootstrap-tgfm9\" ","response":"range_response_count:1 size:8633"} {"level":"info","ts":"2026-07-24T17:52:21.261698Z","caller":"traceutil/trace.go:171","msg":"trace[1718334396] range","detail":"{range_begin:/registry/pods/openstack/cinder-bootstrap-tgfm9; range_end:; response_count:1; response_revision:12445; }","duration":"5.337910192s","start":"2026-07-24T17:52:15.923781Z","end":"2026-07-24T17:52:21.261691Z","steps":["trace[1718334396] 'agreement among raft nodes before linearized reading' (duration: 5.337800659s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.261725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:15.92376Z","time spent":"5.337960344s","remote":"127.0.0.1:52912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":8655,"request content":"key:\"/registry/pods/openstack/cinder-bootstrap-tgfm9\" "} {"level":"warn","ts":"2026-07-24T17:52:21.261745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.5807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T17:52:21.261822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.559733401s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vbwq7\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-07-24T17:52:21.261837Z","caller":"traceutil/trace.go:171","msg":"trace[368373490] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:12445; }","duration":"129.688704ms","start":"2026-07-24T17:52:21.132132Z","end":"2026-07-24T17:52:21.261821Z","steps":["trace[368373490] 'agreement among raft nodes before linearized reading' (duration: 129.522118ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:21.261852Z","caller":"traceutil/trace.go:171","msg":"trace[1272734364] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vbwq7; range_end:; response_count:1; response_revision:12445; }","duration":"4.559775732s","start":"2026-07-24T17:52:16.70207Z","end":"2026-07-24T17:52:21.261845Z","steps":["trace[1272734364] 'agreement among raft nodes before linearized reading' (duration: 4.559643998s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.261826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.326751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T17:52:21.261913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.136320597s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-24T17:52:21.261915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.560699138s","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-07-24T17:52:21.261918Z","caller":"traceutil/trace.go:171","msg":"trace[1704281903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12445; }","duration":"131.476155ms","start":"2026-07-24T17:52:21.130421Z","end":"2026-07-24T17:52:21.261897Z","steps":["trace[1704281903] 'agreement among raft nodes before linearized reading' (duration: 131.309029ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:21.261947Z","caller":"traceutil/trace.go:171","msg":"trace[828726343] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:12445; }","duration":"4.56075176s","start":"2026-07-24T17:52:16.701182Z","end":"2026-07-24T17:52:21.261934Z","steps":["trace[828726343] 'agreement among raft nodes before linearized reading' (duration: 4.560671908s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.261964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.486667459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T17:52:21.261982Z","caller":"traceutil/trace.go:171","msg":"trace[1543507933] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:12445; }","duration":"4.4867059s","start":"2026-07-24T17:52:16.775271Z","end":"2026-07-24T17:52:21.261977Z","steps":["trace[1543507933] 'agreement among raft nodes before linearized reading' (duration: 4.486674249s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:16.77526Z","time spent":"4.486737131s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":9,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:52:21.262021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.583994456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T17:52:21.262054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.395145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-24T17:52:21.262084Z","caller":"traceutil/trace.go:171","msg":"trace[1389965779] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:12445; }","duration":"115.425146ms","start":"2026-07-24T17:52:21.146651Z","end":"2026-07-24T17:52:21.262076Z","steps":["trace[1389965779] 'agreement among raft nodes before linearized reading' (duration: 115.307422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.262093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.515269447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:52:21.26211Z","caller":"traceutil/trace.go:171","msg":"trace[1803124627] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:12445; }","duration":"4.515317739s","start":"2026-07-24T17:52:16.746786Z","end":"2026-07-24T17:52:21.262104Z","steps":["trace[1803124627] 'agreement among raft nodes before linearized reading' (duration: 4.515285268s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.262114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.645535528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-24T17:52:21.262129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:16.746773Z","time spent":"4.51535246s","remote":"127.0.0.1:46552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-07-24T17:52:21.262133Z","caller":"traceutil/trace.go:171","msg":"trace[1421991415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12445; }","duration":"4.64560933s","start":"2026-07-24T17:52:16.616518Z","end":"2026-07-24T17:52:21.262127Z","steps":["trace[1421991415] 'agreement among raft nodes before linearized reading' (duration: 4.645551509s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.262153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:16.616492Z","time spent":"4.645657172s","remote":"127.0.0.1:52996","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":"warn","ts":"2026-07-24T17:52:21.26198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:16.701167Z","time spent":"4.560807241s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-24T17:52:21.262181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.97569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:52:21.262056Z","caller":"traceutil/trace.go:171","msg":"trace[50191380] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:12445; }","duration":"4.584069438s","start":"2026-07-24T17:52:16.677977Z","end":"2026-07-24T17:52:21.262047Z","steps":["trace[50191380] 'agreement among raft nodes before linearized reading' (duration: 4.584015986s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:21.262235Z","caller":"traceutil/trace.go:171","msg":"trace[816316448] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:12445; }","duration":"131.042793ms","start":"2026-07-24T17:52:21.131176Z","end":"2026-07-24T17:52:21.262218Z","steps":["trace[816316448] 'agreement among raft nodes before linearized reading' (duration: 130.96029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.262253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.956490682s","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":"warn","ts":"2026-07-24T17:52:21.26227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:16.677956Z","time spent":"4.584306634s","remote":"127.0.0.1:46512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-07-24T17:52:21.262279Z","caller":"traceutil/trace.go:171","msg":"trace[192596706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12445; }","duration":"4.956526342s","start":"2026-07-24T17:52:16.305746Z","end":"2026-07-24T17:52:21.262272Z","steps":["trace[192596706] 'agreement among raft nodes before linearized reading' (duration: 4.956463691s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.2623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:16.305741Z","time spent":"4.956554964s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-24T17:52:21.262417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.32484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T17:52:21.262463Z","caller":"traceutil/trace.go:171","msg":"trace[772129083] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:12445; }","duration":"131.384202ms","start":"2026-07-24T17:52:21.131066Z","end":"2026-07-24T17:52:21.26245Z","steps":["trace[772129083] 'agreement among raft nodes before linearized reading' (duration: 131.30877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.262513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.200798566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-07-24T17:52:21.262539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.184598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T17:52:21.262542Z","caller":"traceutil/trace.go:171","msg":"trace[1119210282] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12445; }","duration":"5.200852837s","start":"2026-07-24T17:52:16.061683Z","end":"2026-07-24T17:52:21.262536Z","steps":["trace[1119210282] 'agreement among raft nodes before linearized reading' (duration: 5.200756274s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:21.26256Z","caller":"traceutil/trace.go:171","msg":"trace[2064810767] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:12445; }","duration":"130.216659ms","start":"2026-07-24T17:52:21.132338Z","end":"2026-07-24T17:52:21.262554Z","steps":["trace[2064810767] 'agreement among raft nodes before linearized reading' (duration: 130.153377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.262567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:16.061669Z","time spent":"5.200892699s","remote":"127.0.0.1:52912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-24T17:52:21.261876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:16.702039Z","time spent":"4.559831963s","remote":"127.0.0.1:52912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-vbwq7\" "} {"level":"warn","ts":"2026-07-24T17:52:21.262644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.995483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:52:21.26266Z","caller":"traceutil/trace.go:171","msg":"trace[512281680] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:12445; }","duration":"130.020323ms","start":"2026-07-24T17:52:21.132634Z","end":"2026-07-24T17:52:21.262654Z","steps":["trace[512281680] 'agreement among raft nodes before linearized reading' (duration: 129.994292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.262742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.794136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-24T17:52:21.262769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.218099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:52:21.262774Z","caller":"traceutil/trace.go:171","msg":"trace[1830870613] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:12445; }","duration":"129.831587ms","start":"2026-07-24T17:52:21.132935Z","end":"2026-07-24T17:52:21.262767Z","steps":["trace[1830870613] 'agreement among raft nodes before linearized reading' (duration: 129.21925ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:21.262791Z","caller":"traceutil/trace.go:171","msg":"trace[1259999105] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:12445; }","duration":"130.25146ms","start":"2026-07-24T17:52:21.132534Z","end":"2026-07-24T17:52:21.262785Z","steps":["trace[1259999105] 'agreement among raft nodes before linearized reading' (duration: 130.215679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.262809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.95401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-07-24T17:52:21.261934Z","caller":"traceutil/trace.go:171","msg":"trace[31096384] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:12445; }","duration":"4.136344268s","start":"2026-07-24T17:52:17.125584Z","end":"2026-07-24T17:52:21.261928Z","steps":["trace[31096384] 'agreement among raft nodes before linearized reading' (duration: 4.136305826s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:21.262859Z","caller":"traceutil/trace.go:171","msg":"trace[1704599321] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:12445; }","duration":"115.991402ms","start":"2026-07-24T17:52:21.146841Z","end":"2026-07-24T17:52:21.262833Z","steps":["trace[1704599321] 'agreement among raft nodes before linearized reading' (duration: 115.9251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.261975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.24284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-07-24T17:52:21.26289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:17.125535Z","time spent":"4.137343825s","remote":"127.0.0.1:34048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:52:21.262426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.95680066s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:52:21.262921Z","caller":"traceutil/trace.go:171","msg":"trace[672228136] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:12445; }","duration":"116.256599ms","start":"2026-07-24T17:52:21.146656Z","end":"2026-07-24T17:52:21.262912Z","steps":["trace[672228136] 'agreement among raft nodes before linearized reading' (duration: 115.027794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.26293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.488637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T17:52:21.262946Z","caller":"traceutil/trace.go:171","msg":"trace[1255884896] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:12445; }","duration":"130.513667ms","start":"2026-07-24T17:52:21.132427Z","end":"2026-07-24T17:52:21.262941Z","steps":["trace[1255884896] 'agreement among raft nodes before linearized reading' (duration: 130.435005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.262746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.838038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:52:21.263011Z","caller":"traceutil/trace.go:171","msg":"trace[908823650] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:12445; }","duration":"4.957377286s","start":"2026-07-24T17:52:16.305573Z","end":"2026-07-24T17:52:21.26295Z","steps":["trace[908823650] 'agreement among raft nodes before linearized reading' (duration: 4.956823391s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:21.263067Z","caller":"traceutil/trace.go:171","msg":"trace[1066777095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12445; }","duration":"108.207609ms","start":"2026-07-24T17:52:21.154851Z","end":"2026-07-24T17:52:21.263058Z","steps":["trace[1066777095] 'agreement among raft nodes before linearized reading' (duration: 107.861509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.263065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.26589ms","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-07-24T17:52:21.2631Z","caller":"traceutil/trace.go:171","msg":"trace[194551000] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:12445; }","duration":"116.30178ms","start":"2026-07-24T17:52:21.146784Z","end":"2026-07-24T17:52:21.263086Z","steps":["trace[194551000] 'agreement among raft nodes before linearized reading' (duration: 116.219278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:21.263114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:16.305544Z","time spent":"4.957529111s","remote":"127.0.0.1:52842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:52:27.802889Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:28.303978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:28.804099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:29.305139Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:29.806076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:30.249408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999736849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-24T17:52:30.249498Z","caller":"traceutil/trace.go:171","msg":"trace[1303965040] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999881563s","start":"2026-07-24T17:52:28.249595Z","end":"2026-07-24T17:52:30.249476Z","steps":["trace[1303965040] 'agreement among raft nodes before linearized reading' (duration: 1.99977008s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:30.249572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:28.249573Z","time spent":"1.999983067s","remote":"127.0.0.1:52724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/24 17:52:30 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-24T17:52:30.307028Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:30.807375Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:30.970046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999838812s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-24T17:52:30.970126Z","caller":"traceutil/trace.go:171","msg":"trace[1248525348] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999976226s","start":"2026-07-24T17:52:28.970132Z","end":"2026-07-24T17:52:30.970108Z","steps":["trace[1248525348] 'agreement among raft nodes before linearized reading' (duration: 1.999872583s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:30.97018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:28.970108Z","time spent":"2.000056549s","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/24 17:52:30 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-24T17:52:31.307974Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:31.808856Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:32.278097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:27.278934Z","time spent":"4.999149723s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/07/24 17:52:32 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-24T17:52:32.292906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:27.294648Z","time spent":"4.998249516s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/07/24 17:52:32 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-24T17:52:32.304681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998828693s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-24T17:52:32.304774Z","caller":"traceutil/trace.go:171","msg":"trace[2031548431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.998980197s","start":"2026-07-24T17:52:27.305771Z","end":"2026-07-24T17:52:32.304751Z","steps":["trace[2031548431] 'agreement among raft nodes before linearized reading' (duration: 4.998849933s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:32.304827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:27.305752Z","time spent":"4.99906448s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/07/24 17:52:32 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-24T17:52:32.309849Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:32.811001Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:32.966855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999828062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-24T17:52:32.966946Z","caller":"traceutil/trace.go:171","msg":"trace[461021712] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999978166s","start":"2026-07-24T17:52:27.966948Z","end":"2026-07-24T17:52:32.966927Z","steps":["trace[461021712] 'agreement among raft nodes before linearized reading' (duration: 4.999860363s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:32.966994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:27.966926Z","time spent":"5.000057769s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/07/24 17:52:32 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-24T17:52:32.977893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000357487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-24T17:52:32.978012Z","caller":"traceutil/trace.go:171","msg":"trace[1838527203] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000533242s","start":"2026-07-24T17:52:30.977452Z","end":"2026-07-24T17:52:32.977985Z","steps":["trace[1838527203] 'agreement among raft nodes before linearized reading' (duration: 2.000375237s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:32.978085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:30.977435Z","time spent":"2.000635344s","remote":"127.0.0.1:52724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/24 17:52:32 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-24T17:52:33.311471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:33.81234Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:33.869785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999104311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-24T17:52:33.869865Z","caller":"traceutil/trace.go:171","msg":"trace[1978225519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999195403s","start":"2026-07-24T17:52:28.870644Z","end":"2026-07-24T17:52:33.869839Z","steps":["trace[1978225519] 'agreement among raft nodes before linearized reading' (duration: 4.999100161s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:33.869916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:28.870579Z","time spent":"4.999324707s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/07/24 17:52:33 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-24T17:52:34.2811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:27.280509Z","time spent":"7.00058257s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-07-24T17:52:34.302728Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-07-24T17:52:34.302951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.000501538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-07-24T17:52:34.303022Z","caller":"traceutil/trace.go:171","msg":"trace[1442364405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"7.000631641s","start":"2026-07-24T17:52:27.302362Z","end":"2026-07-24T17:52:34.302993Z","steps":["trace[1442364405] 'agreement among raft nodes before linearized reading' (duration: 7.000539249s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:34.303087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:27.302335Z","time spent":"7.000736495s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T17:52:34.802931Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316566,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:34.977965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999881973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-24T17:52:34.978059Z","caller":"traceutil/trace.go:171","msg":"trace[2000589184] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000014377s","start":"2026-07-24T17:52:32.978026Z","end":"2026-07-24T17:52:34.97804Z","steps":["trace[2000589184] 'agreement among raft nodes before linearized reading' (duration: 1.999905994s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:34.978114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:32.978008Z","time spent":"2.00009247s","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/24 17:52:34 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-24T17:52:35.303435Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316566,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:35.766161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:28.765809Z","time spent":"7.000346093s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-07-24T17:52:35.804656Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316566,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:36.305066Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316566,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:36.805267Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860316566,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:52:36.885598Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"9.606490647s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T17:52:36.933073Z","caller":"traceutil/trace.go:171","msg":"trace[1172360508] transaction","detail":"{read_only:false; response_revision:12481; number_of_response:1; }","duration":"6.680324241s","start":"2026-07-24T17:52:30.252725Z","end":"2026-07-24T17:52:36.93305Z","steps":["trace[1172360508] 'process raft request' (duration: 6.679832486s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.933198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:30.252701Z","time spent":"6.680438824s","remote":"127.0.0.1:52790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T17:52:36.933416Z","caller":"traceutil/trace.go:171","msg":"trace[1991509344] transaction","detail":"{read_only:false; response_revision:12482; number_of_response:1; }","duration":"5.49117105s","start":"2026-07-24T17:52:31.442206Z","end":"2026-07-24T17:52:36.933377Z","steps":["trace[1991509344] 'process raft request' (duration: 5.490558763s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:36.933419Z","caller":"traceutil/trace.go:171","msg":"trace[1534686104] transaction","detail":"{read_only:false; response_revision:12483; number_of_response:1; }","duration":"5.402954675s","start":"2026-07-24T17:52:31.530445Z","end":"2026-07-24T17:52:36.9334Z","steps":["trace[1534686104] 'process raft request' (duration: 5.402461301s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.933573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:31.442173Z","time spent":"5.491313494s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:52:36.933643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:31.530424Z","time spent":"5.403168811s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:52:36.986121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000253654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-24T17:52:36.986202Z","caller":"traceutil/trace.go:171","msg":"trace[1446082943] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000413298s","start":"2026-07-24T17:52:34.985772Z","end":"2026-07-24T17:52:36.986185Z","steps":["trace[1446082943] 'agreement among raft nodes before linearized reading' (duration: 2.000285745s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.986247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:34.985755Z","time spent":"2.00048187s","remote":"127.0.0.1:52724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T17:52:36.986232Z","caller":"traceutil/trace.go:171","msg":"trace[2069446517] linearizableReadLoop","detail":"{readStateIndex:12904; appliedIndex:12896; }","duration":"2.683351924s","start":"2026-07-24T17:52:34.302855Z","end":"2026-07-24T17:52:36.986207Z","steps":["trace[2069446517] 'read index received' (duration: 2.583061032s)","trace[2069446517] 'applied index is now lower than readState.Index' (duration: 100.289772ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:52:36.986373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.748232432s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.99\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:52:36.986394Z","caller":"traceutil/trace.go:171","msg":"trace[692611526] range","detail":"{range_begin:/registry/masterleases/199.19.213.99; range_end:; response_count:0; response_revision:12484; }","duration":"5.748308324s","start":"2026-07-24T17:52:31.238078Z","end":"2026-07-24T17:52:36.986387Z","steps":["trace[692611526] 'agreement among raft nodes before linearized reading' (duration: 5.748243112s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.986414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:31.238059Z","time spent":"5.748350325s","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.99\" "} {"level":"warn","ts":"2026-07-24T17:52:36.986508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.508780146s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-07-24T17:52:36.98652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.202772987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T17:52:36.986582Z","caller":"traceutil/trace.go:171","msg":"trace[973228800] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12484; }","duration":"5.508916319s","start":"2026-07-24T17:52:31.477647Z","end":"2026-07-24T17:52:36.986564Z","steps":["trace[973228800] 'agreement among raft nodes before linearized reading' (duration: 5.508642011s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.986586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.680608849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-07-24T17:52:36.986624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:31.477622Z","time spent":"5.508993222s","remote":"127.0.0.1:52912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-24T17:52:36.986638Z","caller":"traceutil/trace.go:171","msg":"trace[694754945] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:12484; }","duration":"9.680669661s","start":"2026-07-24T17:52:27.30595Z","end":"2026-07-24T17:52:36.98662Z","steps":["trace[694754945] 'agreement among raft nodes before linearized reading' (duration: 9.680523697s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.986667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.044348774s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T17:52:36.986672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:27.305942Z","time spent":"9.680723522s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-24T17:52:36.986694Z","caller":"traceutil/trace.go:171","msg":"trace[680545730] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:12484; }","duration":"4.044418366s","start":"2026-07-24T17:52:32.942267Z","end":"2026-07-24T17:52:36.986685Z","steps":["trace[680545730] 'agreement among raft nodes before linearized reading' (duration: 4.044352724s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.986709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:32.942245Z","time spent":"4.044460577s","remote":"127.0.0.1:51042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:52:36.986729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.6927957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-07-24T17:52:36.986761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.679237906s","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":"warn","ts":"2026-07-24T17:52:36.986801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.701954587s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-24T17:52:36.986808Z","caller":"traceutil/trace.go:171","msg":"trace[335937858] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:12484; }","duration":"4.692908984s","start":"2026-07-24T17:52:32.293882Z","end":"2026-07-24T17:52:36.986791Z","steps":["trace[335937858] 'agreement among raft nodes before linearized reading' (duration: 4.692603735s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:36.986851Z","caller":"traceutil/trace.go:171","msg":"trace[685739561] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:12484; }","duration":"2.702027949s","start":"2026-07-24T17:52:34.284805Z","end":"2026-07-24T17:52:36.986833Z","steps":["trace[685739561] 'agreement among raft nodes before linearized reading' (duration: 2.701908155s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.986871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.706674468s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:551"} {"level":"warn","ts":"2026-07-24T17:52:36.986892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:34.284789Z","time spent":"2.70209614s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-24T17:52:36.986893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:32.293862Z","time spent":"4.693019257s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-07-24T17:52:36.9869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.657647621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/cinder-bootstrap-tgfm9\" ","response":"range_response_count:1 size:8633"} {"level":"info","ts":"2026-07-24T17:52:36.986912Z","caller":"traceutil/trace.go:171","msg":"trace[426025033] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:12484; }","duration":"4.70674614s","start":"2026-07-24T17:52:32.280149Z","end":"2026-07-24T17:52:36.986895Z","steps":["trace[426025033] 'agreement among raft nodes before linearized reading' (duration: 4.706684959s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.986928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.778992679s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-07-24T17:52:36.986931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.843422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-24T17:52:36.986932Z","caller":"traceutil/trace.go:171","msg":"trace[11884572] range","detail":"{range_begin:/registry/pods/openstack/cinder-bootstrap-tgfm9; range_end:; response_count:1; response_revision:12484; }","duration":"9.657700433s","start":"2026-07-24T17:52:27.329222Z","end":"2026-07-24T17:52:36.986923Z","steps":["trace[11884572] 'agreement among raft nodes before linearized reading' (duration: 9.657608481s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:36.986955Z","caller":"traceutil/trace.go:171","msg":"trace[4164757] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:12484; }","duration":"689.905084ms","start":"2026-07-24T17:52:36.297042Z","end":"2026-07-24T17:52:36.986948Z","steps":["trace[4164757] 'agreement among raft nodes before linearized reading' (duration: 689.818172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.986953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:32.280121Z","time spent":"4.706822382s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":573,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-07-24T17:52:36.986974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:36.297019Z","time spent":"689.951635ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":580,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-07-24T17:52:36.986973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:27.32921Z","time spent":"9.657748714s","remote":"127.0.0.1:52912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":8655,"request content":"key:\"/registry/pods/openstack/cinder-bootstrap-tgfm9\" "} {"level":"warn","ts":"2026-07-24T17:52:36.986998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114831721s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-24T17:52:36.986768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.434259469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:124616"} {"level":"info","ts":"2026-07-24T17:52:36.987027Z","caller":"traceutil/trace.go:171","msg":"trace[1524668890] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12484; }","duration":"1.114895833s","start":"2026-07-24T17:52:35.872122Z","end":"2026-07-24T17:52:36.987018Z","steps":["trace[1524668890] 'agreement among raft nodes before linearized reading' (duration: 1.114824991s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.98683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.456870666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-07-24T17:52:36.987046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.678923363s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T17:52:36.987067Z","caller":"traceutil/trace.go:171","msg":"trace[1289727897] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:12484; }","duration":"1.678979544s","start":"2026-07-24T17:52:35.308081Z","end":"2026-07-24T17:52:36.98706Z","steps":["trace[1289727897] 'agreement among raft nodes before linearized reading' (duration: 1.678947073s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.987065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:35.872101Z","time spent":"1.114951705s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-24T17:52:36.987204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.212757636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T17:52:36.9872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.826929782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T17:52:36.987258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.018191598s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-24T17:52:36.987254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.67507067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vbwq7\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-07-24T17:52:36.987279Z","caller":"traceutil/trace.go:171","msg":"trace[981913527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12484; }","duration":"2.018250069s","start":"2026-07-24T17:52:34.969022Z","end":"2026-07-24T17:52:36.987272Z","steps":["trace[981913527] 'agreement among raft nodes before linearized reading' (duration: 2.018186217s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.987272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.218347726s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-24T17:52:36.987301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:34.969001Z","time spent":"2.01829606s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-24T17:52:36.987308Z","caller":"traceutil/trace.go:171","msg":"trace[1252219957] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vbwq7; range_end:; response_count:1; response_revision:12484; }","duration":"9.675155763s","start":"2026-07-24T17:52:27.312137Z","end":"2026-07-24T17:52:36.987293Z","steps":["trace[1252219957] 'agreement among raft nodes before linearized reading' (duration: 9.675024739s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:36.986954Z","caller":"traceutil/trace.go:171","msg":"trace[1147141502] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:12484; }","duration":"7.779050091s","start":"2026-07-24T17:52:29.207894Z","end":"2026-07-24T17:52:36.986944Z","steps":["trace[1147141502] 'agreement among raft nodes before linearized reading' (duration: 7.778982749s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:36.987273Z","caller":"traceutil/trace.go:171","msg":"trace[1133158209] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:12484; }","duration":"4.826995574s","start":"2026-07-24T17:52:32.160254Z","end":"2026-07-24T17:52:36.98725Z","steps":["trace[1133158209] 'agreement among raft nodes before linearized reading' (duration: 4.826905061s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.987342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:27.312121Z","time spent":"9.675213794s","remote":"127.0.0.1:52912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-vbwq7\" "} {"level":"warn","ts":"2026-07-24T17:52:36.987356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:29.207874Z","time spent":"7.779477122s","remote":"127.0.0.1:52872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-24T17:52:36.987293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.299802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:52:36.986594Z","caller":"traceutil/trace.go:171","msg":"trace[616860678] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:12484; }","duration":"9.20287398s","start":"2026-07-24T17:52:27.783705Z","end":"2026-07-24T17:52:36.986579Z","steps":["trace[616860678] 'agreement among raft nodes before linearized reading' (duration: 9.202667814s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.98739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:32.16017Z","time spent":"4.827200519s","remote":"127.0.0.1:60324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-24T17:52:36.987437Z","caller":"traceutil/trace.go:171","msg":"trace[381565676] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:12484; }","duration":"159.473396ms","start":"2026-07-24T17:52:36.827952Z","end":"2026-07-24T17:52:36.987425Z","steps":["trace[381565676] 'agreement among raft nodes before linearized reading' (duration: 159.320492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.987453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:27.783636Z","time spent":"9.203802896s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":33,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:52:36.986926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.034163ms","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-07-24T17:52:36.987333Z","caller":"traceutil/trace.go:171","msg":"trace[861456420] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:1; response_revision:12484; }","duration":"1.218423677s","start":"2026-07-24T17:52:35.76889Z","end":"2026-07-24T17:52:36.987314Z","steps":["trace[861456420] 'agreement among raft nodes before linearized reading' (duration: 1.218246592s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:36.987519Z","caller":"traceutil/trace.go:171","msg":"trace[1141514973] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:12484; }","duration":"708.651481ms","start":"2026-07-24T17:52:36.278853Z","end":"2026-07-24T17:52:36.987505Z","steps":["trace[1141514973] 'agreement among raft nodes before linearized reading' (duration: 708.012153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.987529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:35.768877Z","time spent":"1.218640144s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":513,"request content":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" "} {"level":"warn","ts":"2026-07-24T17:52:36.986642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.681628437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:52:36.987569Z","caller":"traceutil/trace.go:171","msg":"trace[1781048951] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:12484; }","duration":"6.682591264s","start":"2026-07-24T17:52:30.304969Z","end":"2026-07-24T17:52:36.98756Z","steps":["trace[1781048951] 'agreement among raft nodes before linearized reading' (duration: 6.681645358s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.987566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:36.278835Z","time spent":"708.719153ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-07-24T17:52:36.987591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:30.304948Z","time spent":"6.682635546s","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:52:36.986865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.768385195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T17:52:36.986517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.027692533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:52:36.987627Z","caller":"traceutil/trace.go:171","msg":"trace[1087447053] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:12484; }","duration":"7.769165937s","start":"2026-07-24T17:52:29.218449Z","end":"2026-07-24T17:52:36.987615Z","steps":["trace[1087447053] 'agreement among raft nodes before linearized reading' (duration: 7.768394355s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.987646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:29.218438Z","time spent":"7.769202289s","remote":"127.0.0.1:49546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:52:36.98704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.494490063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:52:36.987649Z","caller":"traceutil/trace.go:171","msg":"trace[1298378141] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:12484; }","duration":"3.028876746s","start":"2026-07-24T17:52:33.958756Z","end":"2026-07-24T17:52:36.987633Z","steps":["trace[1298378141] 'agreement among raft nodes before linearized reading' (duration: 3.027658272s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.987698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:33.958736Z","time spent":"3.028944799s","remote":"127.0.0.1:58880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"info","ts":"2026-07-24T17:52:36.987692Z","caller":"traceutil/trace.go:171","msg":"trace[1517154350] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:12484; }","duration":"1.495159822s","start":"2026-07-24T17:52:35.492516Z","end":"2026-07-24T17:52:36.987676Z","steps":["trace[1517154350] 'agreement among raft nodes before linearized reading' (duration: 1.494443511s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.987044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.681631968s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-24T17:52:36.987741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:35.492463Z","time spent":"1.495261425s","remote":"127.0.0.1:42726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:52:36.987071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.78450146s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:52:36.987061Z","caller":"traceutil/trace.go:171","msg":"trace[1921732902] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:12484; }","duration":"7.434557626s","start":"2026-07-24T17:52:29.552477Z","end":"2026-07-24T17:52:36.987035Z","steps":["trace[1921732902] 'agreement among raft nodes before linearized reading' (duration: 7.434182176s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:36.987761Z","caller":"traceutil/trace.go:171","msg":"trace[843006380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12484; }","duration":"9.682373208s","start":"2026-07-24T17:52:27.30537Z","end":"2026-07-24T17:52:36.987744Z","steps":["trace[843006380] 'agreement among raft nodes before linearized reading' (duration: 9.681641698s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:36.987793Z","caller":"traceutil/trace.go:171","msg":"trace[1360519973] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:12484; }","duration":"2.785266972s","start":"2026-07-24T17:52:34.202518Z","end":"2026-07-24T17:52:36.987785Z","steps":["trace[1360519973] 'agreement among raft nodes before linearized reading' (duration: 2.784523191s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:36.987067Z","caller":"traceutil/trace.go:171","msg":"trace[297117676] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:12484; }","duration":"7.457141083s","start":"2026-07-24T17:52:29.529915Z","end":"2026-07-24T17:52:36.987056Z","steps":["trace[297117676] 'agreement among raft nodes before linearized reading' (duration: 7.456869035s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.987816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:29.529892Z","time spent":"7.457920955s","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-07-24T17:52:36.987817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:34.202496Z","time spent":"2.785315032s","remote":"127.0.0.1:50958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:52:36.987816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:27.305351Z","time spent":"9.682448481s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T17:52:36.987795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:29.552464Z","time spent":"7.435326219s","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":124638,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-07-24T17:52:36.987184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:35.30806Z","time spent":"1.679021376s","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:52:36.987391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.109176ms","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-07-24T17:52:36.986837Z","caller":"traceutil/trace.go:171","msg":"trace[525764073] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12484; }","duration":"2.679355659s","start":"2026-07-24T17:52:34.30746Z","end":"2026-07-24T17:52:36.986816Z","steps":["trace[525764073] 'agreement among raft nodes before linearized reading' (duration: 2.679144013s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:36.987908Z","caller":"traceutil/trace.go:171","msg":"trace[621091997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12484; }","duration":"678.651262ms","start":"2026-07-24T17:52:36.30924Z","end":"2026-07-24T17:52:36.987891Z","steps":["trace[621091997] 'agreement among raft nodes before linearized reading' (duration: 678.055045ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:36.987263Z","caller":"traceutil/trace.go:171","msg":"trace[847632371] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:12484; }","duration":"4.212848758s","start":"2026-07-24T17:52:32.774397Z","end":"2026-07-24T17:52:36.987246Z","steps":["trace[847632371] 'agreement among raft nodes before linearized reading' (duration: 4.212746046s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.987932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:34.307443Z","time spent":"2.680471521s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-24T17:52:36.987965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:36.309223Z","time spent":"678.724954ms","remote":"127.0.0.1:52996","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":"warn","ts":"2026-07-24T17:52:36.987971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:32.774381Z","time spent":"4.213576869s","remote":"127.0.0.1:46764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:52:36.987005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.465449254s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T17:52:36.988035Z","caller":"traceutil/trace.go:171","msg":"trace[1466737042] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:12484; }","duration":"8.466511954s","start":"2026-07-24T17:52:28.521509Z","end":"2026-07-24T17:52:36.988021Z","steps":["trace[1466737042] 'agreement among raft nodes before linearized reading' (duration: 8.465460245s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:36.988074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:52:28.521489Z","time spent":"8.466572816s","remote":"127.0.0.1:58864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":15,"response size":29,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:52:37.105993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.199141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:63 size:88596"} {"level":"info","ts":"2026-07-24T17:52:37.106074Z","caller":"traceutil/trace.go:171","msg":"trace[59895932] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:63; response_revision:12493; }","duration":"113.309444ms","start":"2026-07-24T17:52:36.992749Z","end":"2026-07-24T17:52:37.106059Z","steps":["trace[59895932] 'agreement among raft nodes before linearized reading' (duration: 112.673416ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:37.106499Z","caller":"traceutil/trace.go:171","msg":"trace[815641621] transaction","detail":"{read_only:false; response_revision:12485; number_of_response:1; }","duration":"113.533192ms","start":"2026-07-24T17:52:36.992947Z","end":"2026-07-24T17:52:37.10648Z","steps":["trace[815641621] 'process raft request' (duration: 99.993684ms)","trace[815641621] 'compare' (duration: 11.988623ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:52:37.106997Z","caller":"traceutil/trace.go:171","msg":"trace[2137733930] transaction","detail":"{read_only:false; response_revision:12486; number_of_response:1; }","duration":"113.970304ms","start":"2026-07-24T17:52:36.993012Z","end":"2026-07-24T17:52:37.106982Z","steps":["trace[2137733930] 'process raft request' (duration: 112.032728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:37.107319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.536953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:52:37.107363Z","caller":"traceutil/trace.go:171","msg":"trace[69172163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12493; }","duration":"111.593555ms","start":"2026-07-24T17:52:36.995758Z","end":"2026-07-24T17:52:37.107351Z","steps":["trace[69172163] 'agreement among raft nodes before linearized reading' (duration: 111.520603ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:37.10772Z","caller":"traceutil/trace.go:171","msg":"trace[1251510689] transaction","detail":"{read_only:false; response_revision:12487; number_of_response:1; }","duration":"114.616222ms","start":"2026-07-24T17:52:36.993087Z","end":"2026-07-24T17:52:37.107703Z","steps":["trace[1251510689] 'process raft request' (duration: 112.019118ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:37.108027Z","caller":"traceutil/trace.go:171","msg":"trace[997459385] transaction","detail":"{read_only:false; response_revision:12488; number_of_response:1; }","duration":"114.88849ms","start":"2026-07-24T17:52:36.993123Z","end":"2026-07-24T17:52:37.108012Z","steps":["trace[997459385] 'process raft request' (duration: 112.016508ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:37.108199Z","caller":"traceutil/trace.go:171","msg":"trace[134910879] transaction","detail":"{read_only:false; response_revision:12489; number_of_response:1; }","duration":"112.887832ms","start":"2026-07-24T17:52:36.995254Z","end":"2026-07-24T17:52:37.108142Z","steps":["trace[134910879] 'process raft request' (duration: 109.917497ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:37.108371Z","caller":"traceutil/trace.go:171","msg":"trace[2102311399] transaction","detail":"{read_only:false; response_revision:12490; number_of_response:1; }","duration":"113.028256ms","start":"2026-07-24T17:52:36.99533Z","end":"2026-07-24T17:52:37.108359Z","steps":["trace[2102311399] 'process raft request' (duration: 109.874596ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:37.108687Z","caller":"traceutil/trace.go:171","msg":"trace[2093804312] transaction","detail":"{read_only:false; response_revision:12491; number_of_response:1; }","duration":"113.237002ms","start":"2026-07-24T17:52:36.99543Z","end":"2026-07-24T17:52:37.108667Z","steps":["trace[2093804312] 'process raft request' (duration: 109.808854ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:37.109036Z","caller":"traceutil/trace.go:171","msg":"trace[2139856215] transaction","detail":"{read_only:false; response_revision:12492; number_of_response:1; }","duration":"113.433238ms","start":"2026-07-24T17:52:36.995586Z","end":"2026-07-24T17:52:37.10902Z","steps":["trace[2139856215] 'process raft request' (duration: 109.685401ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:37.109247Z","caller":"traceutil/trace.go:171","msg":"trace[420622357] transaction","detail":"{read_only:false; response_revision:12493; number_of_response:1; }","duration":"113.430438ms","start":"2026-07-24T17:52:36.995805Z","end":"2026-07-24T17:52:37.109236Z","steps":["trace[420622357] 'process raft request' (duration: 109.536246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:37.109547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.561873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-24T17:52:37.109606Z","caller":"traceutil/trace.go:171","msg":"trace[1391269376] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:12493; }","duration":"112.648786ms","start":"2026-07-24T17:52:36.996945Z","end":"2026-07-24T17:52:37.109594Z","steps":["trace[1391269376] 'agreement among raft nodes before linearized reading' (duration: 112.497742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:37.109971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.752137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-24T17:52:37.110116Z","caller":"traceutil/trace.go:171","msg":"trace[357429467] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:12493; }","duration":"113.809819ms","start":"2026-07-24T17:52:36.996196Z","end":"2026-07-24T17:52:37.110005Z","steps":["trace[357429467] 'agreement among raft nodes before linearized reading' (duration: 113.696715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:37.110684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.559166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-24T17:52:37.110948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.850685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-24T17:52:37.110991Z","caller":"traceutil/trace.go:171","msg":"trace[11802407] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:12493; }","duration":"117.910966ms","start":"2026-07-24T17:52:36.993067Z","end":"2026-07-24T17:52:37.110978Z","steps":["trace[11802407] 'agreement among raft nodes before linearized reading' (duration: 117.769632ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:52:37.110751Z","caller":"traceutil/trace.go:171","msg":"trace[360026831] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:1; response_revision:12493; }","duration":"117.638988ms","start":"2026-07-24T17:52:36.993095Z","end":"2026-07-24T17:52:37.110734Z","steps":["trace[360026831] 'agreement among raft nodes before linearized reading' (duration: 117.503614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:37.112525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.486774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-24T17:52:37.112552Z","caller":"traceutil/trace.go:171","msg":"trace[1677130779] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12493; }","duration":"117.525056ms","start":"2026-07-24T17:52:36.995019Z","end":"2026-07-24T17:52:37.112544Z","steps":["trace[1677130779] 'agreement among raft nodes before linearized reading' (duration: 117.453294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:37.113117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.098762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:56 size:524834"} {"level":"info","ts":"2026-07-24T17:52:37.113147Z","caller":"traceutil/trace.go:171","msg":"trace[1128913024] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:56; response_revision:12493; }","duration":"118.156203ms","start":"2026-07-24T17:52:36.994984Z","end":"2026-07-24T17:52:37.11314Z","steps":["trace[1128913024] 'agreement among raft nodes before linearized reading' (duration: 117.732781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:37.113714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.737779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:56 size:524834"} {"level":"info","ts":"2026-07-24T17:52:37.113748Z","caller":"traceutil/trace.go:171","msg":"trace[591927509] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:56; response_revision:12493; }","duration":"118.795801ms","start":"2026-07-24T17:52:36.994946Z","end":"2026-07-24T17:52:37.113742Z","steps":["trace[591927509] 'agreement among raft nodes before linearized reading' (duration: 118.482853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:52:37.11431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.614404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:551"} {"level":"info","ts":"2026-07-24T17:52:37.114349Z","caller":"traceutil/trace.go:171","msg":"trace[1733495546] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:12493; }","duration":"120.661905ms","start":"2026-07-24T17:52:36.99367Z","end":"2026-07-24T17:52:37.114332Z","steps":["trace[1733495546] 'agreement among raft nodes before linearized reading' (duration: 120.593443ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:53:28.234628Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10806} {"level":"info","ts":"2026-07-24T17:53:28.316349Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10806,"took":"79.099176ms","hash":288295538,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":27881472,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-24T17:53:28.316395Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":288295538,"revision":10806,"compact-revision":8308} {"level":"info","ts":"2026-07-24T17:55:37.374336Z","caller":"traceutil/trace.go:171","msg":"trace[485486077] linearizableReadLoop","detail":"{readStateIndex:14232; appliedIndex:14231; }","duration":"135.504662ms","start":"2026-07-24T17:55:37.238806Z","end":"2026-07-24T17:55:37.374311Z","steps":["trace[485486077] 'read index received' (duration: 135.40517ms)","trace[485486077] 'applied index is now lower than readState.Index' (duration: 98.802µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:55:37.374391Z","caller":"traceutil/trace.go:171","msg":"trace[1467835573] transaction","detail":"{read_only:false; response_revision:13765; number_of_response:1; }","duration":"138.367476ms","start":"2026-07-24T17:55:37.235988Z","end":"2026-07-24T17:55:37.374356Z","steps":["trace[1467835573] 'process raft request' (duration: 138.191641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:55:37.374513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.683988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-24T17:55:37.374546Z","caller":"traceutil/trace.go:171","msg":"trace[788255031] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:13765; }","duration":"135.74841ms","start":"2026-07-24T17:55:37.238788Z","end":"2026-07-24T17:55:37.374536Z","steps":["trace[788255031] 'agreement among raft nodes before linearized reading' (duration: 135.621596ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:55:37.686344Z","caller":"traceutil/trace.go:171","msg":"trace[386028340] transaction","detail":"{read_only:false; response_revision:13766; number_of_response:1; }","duration":"411.913886ms","start":"2026-07-24T17:55:37.274401Z","end":"2026-07-24T17:55:37.686315Z","steps":["trace[386028340] 'process raft request' (duration: 409.852587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:55:37.686524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:55:37.274372Z","time spent":"412.07047ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:55:37.687009Z","caller":"traceutil/trace.go:171","msg":"trace[62625783] transaction","detail":"{read_only:false; response_revision:13767; number_of_response:1; }","duration":"307.962966ms","start":"2026-07-24T17:55:37.379Z","end":"2026-07-24T17:55:37.686963Z","steps":["trace[62625783] 'process raft request' (duration: 307.253895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:55:37.687245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:55:37.378974Z","time spent":"308.143181ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:56:22.770589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.751123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:56:22.770728Z","caller":"traceutil/trace.go:171","msg":"trace[916232723] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:14232; }","duration":"123.949199ms","start":"2026-07-24T17:56:22.646754Z","end":"2026-07-24T17:56:22.770703Z","steps":["trace[916232723] 'count revisions from in-memory index tree' (duration: 123.669681ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:56:24.188201Z","caller":"traceutil/trace.go:171","msg":"trace[724621485] linearizableReadLoop","detail":"{readStateIndex:14719; appliedIndex:14718; }","duration":"463.992945ms","start":"2026-07-24T17:56:23.72418Z","end":"2026-07-24T17:56:24.188173Z","steps":["trace[724621485] 'read index received' (duration: 462.966016ms)","trace[724621485] 'applied index is now lower than readState.Index' (duration: 1.025549ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:56:24.188287Z","caller":"traceutil/trace.go:171","msg":"trace[418757524] transaction","detail":"{read_only:false; response_revision:14235; number_of_response:1; }","duration":"472.610604ms","start":"2026-07-24T17:56:23.71564Z","end":"2026-07-24T17:56:24.18825Z","steps":["trace[418757524] 'process raft request' (duration: 472.299735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:56:24.188461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.232762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:56:24.188771Z","caller":"traceutil/trace.go:171","msg":"trace[1767503346] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:14235; }","duration":"464.584473ms","start":"2026-07-24T17:56:23.724163Z","end":"2026-07-24T17:56:24.188747Z","steps":["trace[1767503346] 'agreement among raft nodes before linearized reading' (duration: 464.13737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:56:24.188707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:56:23.71562Z","time spent":"472.961455ms","remote":"127.0.0.1:52912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10619,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:56:24.18894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:56:23.724155Z","time spent":"464.765237ms","remote":"127.0.0.1:58900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-24T17:56:24.210002Z","caller":"traceutil/trace.go:171","msg":"trace[458936923] transaction","detail":"{read_only:false; response_revision:14236; number_of_response:1; }","duration":"256.24122ms","start":"2026-07-24T17:56:23.953739Z","end":"2026-07-24T17:56:24.20998Z","steps":["trace[458936923] 'process raft request' (duration: 256.027293ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:56:24.210003Z","caller":"traceutil/trace.go:171","msg":"trace[983068646] transaction","detail":"{read_only:false; response_revision:14237; number_of_response:1; }","duration":"100.167321ms","start":"2026-07-24T17:56:24.109815Z","end":"2026-07-24T17:56:24.209983Z","steps":["trace[983068646] 'process raft request' (duration: 100.089948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:56:24.210312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.516402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1849"} {"level":"info","ts":"2026-07-24T17:56:24.210346Z","caller":"traceutil/trace.go:171","msg":"trace[1432661928] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:14238; }","duration":"433.590524ms","start":"2026-07-24T17:56:23.776745Z","end":"2026-07-24T17:56:24.210335Z","steps":["trace[1432661928] 'agreement among raft nodes before linearized reading' (duration: 433.421499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:56:24.21037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:56:23.776728Z","time spent":"433.633695ms","remote":"127.0.0.1:52872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1871,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" "} {"level":"warn","ts":"2026-07-24T17:57:25.088674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.771637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:57:25.088822Z","caller":"traceutil/trace.go:171","msg":"trace[1830883386] linearizableReadLoop","detail":"{readStateIndex:15382; appliedIndex:15381; }","duration":"300.564187ms","start":"2026-07-24T17:57:24.788234Z","end":"2026-07-24T17:57:25.088798Z","steps":["trace[1830883386] 'read index received' (duration: 122.613494ms)","trace[1830883386] 'applied index is now lower than readState.Index' (duration: 177.949233ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:57:25.088863Z","caller":"traceutil/trace.go:171","msg":"trace[902614728] transaction","detail":"{read_only:false; response_revision:14882; number_of_response:1; }","duration":"382.044112ms","start":"2026-07-24T17:57:24.706779Z","end":"2026-07-24T17:57:25.088823Z","steps":["trace[902614728] 'process raft request' (duration: 204.018247ms)","trace[902614728] 'compare' (duration: 177.449258ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:57:25.089045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:57:24.70675Z","time spent":"382.201846ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:57:25.089093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.9799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T17:57:25.089191Z","caller":"traceutil/trace.go:171","msg":"trace[1249153676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14882; }","duration":"300.110184ms","start":"2026-07-24T17:57:24.78906Z","end":"2026-07-24T17:57:25.089171Z","steps":["trace[1249153676] 'agreement among raft nodes before linearized reading' (duration: 299.876147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:57:25.089157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.899686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-24T17:57:25.089237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:57:24.789054Z","time spent":"300.176995ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-24T17:57:25.089279Z","caller":"traceutil/trace.go:171","msg":"trace[980204943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14882; }","duration":"301.071341ms","start":"2026-07-24T17:57:24.78818Z","end":"2026-07-24T17:57:25.089251Z","steps":["trace[980204943] 'agreement among raft nodes before linearized reading' (duration: 300.734811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:57:25.089335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.040502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:57:25.089376Z","caller":"traceutil/trace.go:171","msg":"trace[1953349579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14882; }","duration":"118.158166ms","start":"2026-07-24T17:57:24.971206Z","end":"2026-07-24T17:57:25.089364Z","steps":["trace[1953349579] 'agreement among raft nodes before linearized reading' (duration: 118.054442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:57:25.089382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:57:24.788159Z","time spent":"301.205796ms","remote":"127.0.0.1:52996","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":"warn","ts":"2026-07-24T17:57:25.089408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.444913ms","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-07-24T17:57:25.08944Z","caller":"traceutil/trace.go:171","msg":"trace[756646336] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14882; }","duration":"200.513286ms","start":"2026-07-24T17:57:24.888918Z","end":"2026-07-24T17:57:25.089431Z","steps":["trace[756646336] 'agreement among raft nodes before linearized reading' (duration: 200.431784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:57:25.089582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.325637ms","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-07-24T17:57:25.089647Z","caller":"traceutil/trace.go:171","msg":"trace[115433549] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14882; }","duration":"203.455231ms","start":"2026-07-24T17:57:24.886172Z","end":"2026-07-24T17:57:25.089627Z","steps":["trace[115433549] 'agreement among raft nodes before linearized reading' (duration: 203.196223ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:57:25.213094Z","caller":"traceutil/trace.go:171","msg":"trace[380878516] transaction","detail":"{read_only:false; response_revision:14883; number_of_response:1; }","duration":"117.070994ms","start":"2026-07-24T17:57:25.095972Z","end":"2026-07-24T17:57:25.213043Z","steps":["trace[380878516] 'process raft request' (duration: 106.470567ms)","trace[380878516] 'compare' (duration: 10.464533ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:57:25.229481Z","caller":"traceutil/trace.go:171","msg":"trace[1992540296] linearizableReadLoop","detail":"{readStateIndex:15386; appliedIndex:15382; }","duration":"101.973648ms","start":"2026-07-24T17:57:25.127475Z","end":"2026-07-24T17:57:25.229449Z","steps":["trace[1992540296] 'read index received' (duration: 74.991158ms)","trace[1992540296] 'applied index is now lower than readState.Index' (duration: 26.98158ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:57:25.229619Z","caller":"traceutil/trace.go:171","msg":"trace[74594619] transaction","detail":"{read_only:false; response_revision:14886; number_of_response:1; }","duration":"132.914073ms","start":"2026-07-24T17:57:25.096692Z","end":"2026-07-24T17:57:25.229607Z","steps":["trace[74594619] 'process raft request' (duration: 132.717657ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:57:25.229728Z","caller":"traceutil/trace.go:171","msg":"trace[735103699] transaction","detail":"{read_only:false; response_revision:14885; number_of_response:1; }","duration":"133.199611ms","start":"2026-07-24T17:57:25.096308Z","end":"2026-07-24T17:57:25.229508Z","steps":["trace[735103699] 'process raft request' (duration: 133.052967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:57:25.230071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.575155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-zqs86\" ","response":"range_response_count:1 size:20656"} {"level":"info","ts":"2026-07-24T17:57:25.229838Z","caller":"traceutil/trace.go:171","msg":"trace[1131050695] transaction","detail":"{read_only:false; response_revision:14884; number_of_response:1; }","duration":"133.614193ms","start":"2026-07-24T17:57:25.096192Z","end":"2026-07-24T17:57:25.229806Z","steps":["trace[1131050695] 'process raft request' (duration: 132.990974ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:57:25.230143Z","caller":"traceutil/trace.go:171","msg":"trace[250675046] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-zqs86; range_end:; response_count:1; response_revision:14886; }","duration":"102.72031ms","start":"2026-07-24T17:57:25.12741Z","end":"2026-07-24T17:57:25.23013Z","steps":["trace[250675046] 'agreement among raft nodes before linearized reading' (duration: 102.510604ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:58:28.243838Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12921} {"level":"info","ts":"2026-07-24T17:58:28.330434Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12921,"took":"83.447881ms","hash":3951769446,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":30470144,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-24T17:58:28.330503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3951769446,"revision":12921,"compact-revision":10806} {"level":"info","ts":"2026-07-24T18:03:27.103476Z","caller":"traceutil/trace.go:171","msg":"trace[1145078521] linearizableReadLoop","detail":"{readStateIndex:18171; appliedIndex:18170; }","duration":"131.434738ms","start":"2026-07-24T18:03:26.972016Z","end":"2026-07-24T18:03:27.103451Z","steps":["trace[1145078521] 'read index received' (duration: 131.17689ms)","trace[1145078521] 'applied index is now lower than readState.Index' (duration: 256.498µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T18:03:27.103548Z","caller":"traceutil/trace.go:171","msg":"trace[1365758204] transaction","detail":"{read_only:false; response_revision:17582; number_of_response:1; }","duration":"197.938864ms","start":"2026-07-24T18:03:26.905564Z","end":"2026-07-24T18:03:27.103503Z","steps":["trace[1365758204] 'process raft request' (duration: 197.700817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:03:27.10374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.680434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:03:27.103852Z","caller":"traceutil/trace.go:171","msg":"trace[1405254528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17582; }","duration":"131.851499ms","start":"2026-07-24T18:03:26.971975Z","end":"2026-07-24T18:03:27.103827Z","steps":["trace[1405254528] 'agreement among raft nodes before linearized reading' (duration: 131.623563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:03:27.578433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.64021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-24T18:03:27.578502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.684342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-24T18:03:27.578556Z","caller":"traceutil/trace.go:171","msg":"trace[1534699603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17583; }","duration":"167.779975ms","start":"2026-07-24T18:03:27.410731Z","end":"2026-07-24T18:03:27.578511Z","steps":["trace[1534699603] 'range keys from in-memory index tree' (duration: 167.468126ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T18:03:27.578581Z","caller":"traceutil/trace.go:171","msg":"trace[483497753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17583; }","duration":"150.793895ms","start":"2026-07-24T18:03:27.427775Z","end":"2026-07-24T18:03:27.578569Z","steps":["trace[483497753] 'range keys from in-memory index tree' (duration: 150.548778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:03:27.579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.190898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-24T18:03:27.579097Z","caller":"traceutil/trace.go:171","msg":"trace[1647515750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17583; }","duration":"133.342973ms","start":"2026-07-24T18:03:27.445734Z","end":"2026-07-24T18:03:27.579077Z","steps":["trace[1647515750] 'range keys from in-memory index tree' (duration: 133.018733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:03:27.579036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.80267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-24T18:03:27.579202Z","caller":"traceutil/trace.go:171","msg":"trace[64560701] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17583; }","duration":"180.010237ms","start":"2026-07-24T18:03:27.399175Z","end":"2026-07-24T18:03:27.579185Z","steps":["trace[64560701] 'range keys from in-memory index tree' (duration: 179.679687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:03:28.095637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.189212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29748600-n9n5h\" ","response":"range_response_count:1 size:7037"} {"level":"info","ts":"2026-07-24T18:03:28.095773Z","caller":"traceutil/trace.go:171","msg":"trace[1163491945] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29748600-n9n5h; range_end:; response_count:1; response_revision:17587; }","duration":"365.355026ms","start":"2026-07-24T18:03:27.730359Z","end":"2026-07-24T18:03:28.095714Z","steps":["trace[1163491945] 'range keys from in-memory index tree' (duration: 364.955935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:03:28.09583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:03:27.730336Z","time spent":"365.48085ms","remote":"127.0.0.1:52912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":7059,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-29748600-n9n5h\" "} {"level":"info","ts":"2026-07-24T18:03:28.096378Z","caller":"traceutil/trace.go:171","msg":"trace[643861186] transaction","detail":"{read_only:false; response_revision:17588; number_of_response:1; }","duration":"166.897509ms","start":"2026-07-24T18:03:27.929448Z","end":"2026-07-24T18:03:28.096346Z","steps":["trace[643861186] 'process raft request' (duration: 136.157624ms)","trace[643861186] 'compare' (duration: 30.357394ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T18:03:28.607797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.501443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-n6fnf\" ","response":"range_response_count:1 size:22129"} {"level":"info","ts":"2026-07-24T18:03:28.607921Z","caller":"traceutil/trace.go:171","msg":"trace[1807382415] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-n6fnf; range_end:; response_count:1; response_revision:17590; }","duration":"389.692309ms","start":"2026-07-24T18:03:28.218202Z","end":"2026-07-24T18:03:28.607894Z","steps":["trace[1807382415] 'range keys from in-memory index tree' (duration: 389.231336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:03:28.607973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.240671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-24T18:03:28.607987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:03:28.218176Z","time spent":"389.800632ms","remote":"127.0.0.1:52912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22151,"request content":"key:\"/registry/pods/openstack/nova-compute-default-n6fnf\" "} {"level":"info","ts":"2026-07-24T18:03:28.608088Z","caller":"traceutil/trace.go:171","msg":"trace[38398898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17590; }","duration":"359.420267ms","start":"2026-07-24T18:03:28.24865Z","end":"2026-07-24T18:03:28.608071Z","steps":["trace[38398898] 'range keys from in-memory index tree' (duration: 357.47823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:03:28.60813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:03:28.248626Z","time spent":"359.497828ms","remote":"127.0.0.1:52724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T18:03:28.608596Z","caller":"traceutil/trace.go:171","msg":"trace[101227109] transaction","detail":"{read_only:false; response_revision:17591; number_of_response:1; }","duration":"359.362584ms","start":"2026-07-24T18:03:28.249217Z","end":"2026-07-24T18:03:28.608579Z","steps":["trace[101227109] 'process raft request' (duration: 271.508163ms)","trace[101227109] 'compare' (duration: 86.716899ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T18:03:28.608686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:03:28.24919Z","time spent":"359.437857ms","remote":"127.0.0.1:52750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":26,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T18:03:28.861519Z","caller":"traceutil/trace.go:171","msg":"trace[1960968477] linearizableReadLoop","detail":"{readStateIndex:18181; appliedIndex:18180; }","duration":"245.171635ms","start":"2026-07-24T18:03:28.61631Z","end":"2026-07-24T18:03:28.861482Z","steps":["trace[1960968477] 'read index received' (duration: 163.418158ms)","trace[1960968477] 'applied index is now lower than readState.Index' (duration: 81.751216ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T18:03:28.861539Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15296} {"level":"info","ts":"2026-07-24T18:03:28.861674Z","caller":"traceutil/trace.go:171","msg":"trace[225555264] compact","detail":"{revision:15296; response_revision:17591; }","duration":"251.731603ms","start":"2026-07-24T18:03:28.609884Z","end":"2026-07-24T18:03:28.861616Z","steps":["trace[225555264] 'process raft request' (duration: 169.766061ms)","trace[225555264] 'check and update compact revision' (duration: 81.691125ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T18:03:28.863472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.148311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:961504"} {"level":"info","ts":"2026-07-24T18:03:28.863535Z","caller":"traceutil/trace.go:171","msg":"trace[1862343452] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:17591; }","duration":"247.277444ms","start":"2026-07-24T18:03:28.616242Z","end":"2026-07-24T18:03:28.86352Z","steps":["trace[1862343452] 'agreement among raft nodes before linearized reading' (duration: 245.398231ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T18:03:28.974192Z","caller":"traceutil/trace.go:171","msg":"trace[1728867350] transaction","detail":"{read_only:false; response_revision:17592; number_of_response:1; }","duration":"100.443294ms","start":"2026-07-24T18:03:28.873718Z","end":"2026-07-24T18:03:28.974161Z","steps":["trace[1728867350] 'process raft request' (duration: 72.291813ms)","trace[1728867350] 'compare' (duration: 27.935435ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T18:03:29.087098Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15296,"took":"220.794902ms","hash":3408205559,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":31903744,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-24T18:03:29.087189Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3408205559,"revision":15296,"compact-revision":12921} {"level":"warn","ts":"2026-07-24T18:04:28.050333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.313877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-24T18:04:28.050414Z","caller":"traceutil/trace.go:171","msg":"trace[273623839] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18270; }","duration":"440.463811ms","start":"2026-07-24T18:04:27.609936Z","end":"2026-07-24T18:04:28.0504Z","steps":["trace[273623839] 'range keys from in-memory index tree' (duration: 440.177653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:28.050437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:27.60991Z","time spent":"440.521532ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T18:04:29.278356Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860336727,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T18:04:29.778889Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860336727,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T18:04:31.459156Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860336727,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T18:04:31.757213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.158563877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-24T18:04:31.759952Z","caller":"traceutil/trace.go:171","msg":"trace[447435542] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.158838525s","start":"2026-07-24T18:04:29.598508Z","end":"2026-07-24T18:04:31.757347Z","steps":["trace[447435542] 'agreement among raft nodes before linearized reading' (duration: 2.158614259s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.760091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:29.598487Z","time spent":"2.161557623s","remote":"127.0.0.1:52724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T18:04:31.809546Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.514127566s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-24T18:04:31.848815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.623610742s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:04:31.848898Z","caller":"traceutil/trace.go:171","msg":"trace[324971424] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18275; }","duration":"3.62387772s","start":"2026-07-24T18:04:28.224999Z","end":"2026-07-24T18:04:31.848876Z","steps":["trace[324971424] 'range keys from in-memory index tree' (duration: 3.623281952s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.848934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:28.224978Z","time spent":"3.623948282s","remote":"127.0.0.1:53178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-24T18:04:31.849886Z","caller":"traceutil/trace.go:171","msg":"trace[1032256925] transaction","detail":"{read_only:false; response_revision:18276; number_of_response:1; }","duration":"3.554555421s","start":"2026-07-24T18:04:28.295298Z","end":"2026-07-24T18:04:31.849853Z","steps":["trace[1032256925] 'process raft request' (duration: 3.514742594s)","trace[1032256925] 'compare' (duration: 36.995215ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T18:04:31.849896Z","caller":"traceutil/trace.go:171","msg":"trace[851654566] transaction","detail":"{read_only:false; response_revision:18279; number_of_response:1; }","duration":"2.258330682s","start":"2026-07-24T18:04:29.59153Z","end":"2026-07-24T18:04:31.84986Z","steps":["trace[851654566] 'process raft request' (duration: 2.25825687s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.850017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:28.29527Z","time spent":"3.554680426s","remote":"127.0.0.1:60334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2237,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T18:04:31.850077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:29.591503Z","time spent":"2.258468866s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T18:04:31.850177Z","caller":"traceutil/trace.go:171","msg":"trace[1216187188] transaction","detail":"{read_only:false; response_revision:18277; number_of_response:1; }","duration":"3.016023804s","start":"2026-07-24T18:04:28.834145Z","end":"2026-07-24T18:04:31.850169Z","steps":["trace[1216187188] 'process raft request' (duration: 3.015486618s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.850221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:28.834124Z","time spent":"3.016069355s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T18:04:31.85031Z","caller":"traceutil/trace.go:171","msg":"trace[1767109212] transaction","detail":"{read_only:false; response_revision:18278; number_of_response:1; }","duration":"3.015117019s","start":"2026-07-24T18:04:28.83518Z","end":"2026-07-24T18:04:31.850297Z","steps":["trace[1767109212] 'process raft request' (duration: 3.014572313s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.85037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:28.835166Z","time spent":"3.01516187s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T18:04:31.85039Z","caller":"traceutil/trace.go:171","msg":"trace[685689405] linearizableReadLoop","detail":"{readStateIndex:18889; appliedIndex:18885; }","duration":"3.07240142s","start":"2026-07-24T18:04:28.777978Z","end":"2026-07-24T18:04:31.850379Z","steps":["trace[685689405] 'read index received' (duration: 3.032085197s)","trace[685689405] 'applied index is now lower than readState.Index' (duration: 40.315603ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T18:04:31.850581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.072503672s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-24T18:04:31.850598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.260125445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:04:31.850646Z","caller":"traceutil/trace.go:171","msg":"trace[280241190] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:18282; }","duration":"2.260190396s","start":"2026-07-24T18:04:29.590445Z","end":"2026-07-24T18:04:31.850636Z","steps":["trace[280241190] 'agreement among raft nodes before linearized reading' (duration: 2.260119434s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.850687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.260245398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:1 size:4959"} {"level":"info","ts":"2026-07-24T18:04:31.850714Z","caller":"traceutil/trace.go:171","msg":"trace[538659075] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:1; response_revision:18282; }","duration":"2.260299189s","start":"2026-07-24T18:04:29.590411Z","end":"2026-07-24T18:04:31.85071Z","steps":["trace[538659075] 'agreement among raft nodes before linearized reading' (duration: 2.260210277s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.850727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:29.590408Z","time spent":"2.26031559s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4981,"request content":"key:\"/registry/jobs/openstack/heat-rabbit-init\" "} {"level":"warn","ts":"2026-07-24T18:04:31.85073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.967985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:04:31.850758Z","caller":"traceutil/trace.go:171","msg":"trace[941702297] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:18282; }","duration":"392.011276ms","start":"2026-07-24T18:04:31.45874Z","end":"2026-07-24T18:04:31.850751Z","steps":["trace[941702297] 'agreement among raft nodes before linearized reading' (duration: 391.960645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.850785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:31.458734Z","time spent":"392.043027ms","remote":"127.0.0.1:53116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-07-24T18:04:31.85082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.260412813s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:04:31.850833Z","caller":"traceutil/trace.go:171","msg":"trace[1636974996] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:18282; }","duration":"2.260433114s","start":"2026-07-24T18:04:29.590396Z","end":"2026-07-24T18:04:31.850829Z","steps":["trace[1636974996] 'agreement among raft nodes before linearized reading' (duration: 2.260410683s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.850846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:29.590393Z","time spent":"2.260449984s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"warn","ts":"2026-07-24T18:04:31.850909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.260516456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:04:31.850921Z","caller":"traceutil/trace.go:171","msg":"trace[1252376444] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:18282; }","duration":"2.260534476s","start":"2026-07-24T18:04:29.590383Z","end":"2026-07-24T18:04:31.850917Z","steps":["trace[1252376444] 'agreement among raft nodes before linearized reading' (duration: 2.260515496s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.850935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:29.590379Z","time spent":"2.260553716s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" "} {"level":"warn","ts":"2026-07-24T18:04:31.850996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.260617388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-24T18:04:31.851001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.348166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.99\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-24T18:04:31.851026Z","caller":"traceutil/trace.go:171","msg":"trace[615533106] range","detail":"{range_begin:/registry/masterleases/199.19.213.99; range_end:; response_count:1; response_revision:18282; }","duration":"392.387647ms","start":"2026-07-24T18:04:31.458631Z","end":"2026-07-24T18:04:31.851018Z","steps":["trace[615533106] 'agreement among raft nodes before linearized reading' (duration: 392.300174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.851046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:31.458626Z","time spent":"392.415058ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.99\" "} {"level":"warn","ts":"2026-07-24T18:04:31.851063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.260734032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:1 size:5129"} {"level":"info","ts":"2026-07-24T18:04:31.851075Z","caller":"traceutil/trace.go:171","msg":"trace[1453603936] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:1; response_revision:18282; }","duration":"2.260794773s","start":"2026-07-24T18:04:29.590276Z","end":"2026-07-24T18:04:31.851071Z","steps":["trace[1453603936] 'agreement among raft nodes before linearized reading' (duration: 2.260741412s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.851086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:29.590257Z","time spent":"2.260826545s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5151,"request content":"key:\"/registry/jobs/openstack/heat-db-sync\" "} {"level":"warn","ts":"2026-07-24T18:04:31.851175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.491232333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-24T18:04:31.851191Z","caller":"traceutil/trace.go:171","msg":"trace[1167533492] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18282; }","duration":"2.491248284s","start":"2026-07-24T18:04:29.359937Z","end":"2026-07-24T18:04:31.851185Z","steps":["trace[1167533492] 'agreement among raft nodes before linearized reading' (duration: 2.491205932s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.851206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:29.359859Z","time spent":"2.491344166s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-24T18:04:31.851215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.590852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-24T18:04:31.851246Z","caller":"traceutil/trace.go:171","msg":"trace[1289925722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18282; }","duration":"392.636504ms","start":"2026-07-24T18:04:31.458602Z","end":"2026-07-24T18:04:31.851239Z","steps":["trace[1289925722] 'agreement among raft nodes before linearized reading' (duration: 392.542392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.851341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.918934107s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29748600-n9n5h\" ","response":"range_response_count:1 size:7037"} {"level":"warn","ts":"2026-07-24T18:04:31.851347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:31.458597Z","time spent":"392.674415ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-24T18:04:31.851357Z","caller":"traceutil/trace.go:171","msg":"trace[1155258493] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29748600-n9n5h; range_end:; response_count:1; response_revision:18282; }","duration":"2.918981568s","start":"2026-07-24T18:04:28.93237Z","end":"2026-07-24T18:04:31.851351Z","steps":["trace[1155258493] 'agreement among raft nodes before linearized reading' (duration: 2.918881876s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.851371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:28.932351Z","time spent":"2.91901666s","remote":"127.0.0.1:52912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":7059,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-29748600-n9n5h\" "} {"level":"warn","ts":"2026-07-24T18:04:31.851399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.51561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-hqr5r\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-07-24T18:04:31.851429Z","caller":"traceutil/trace.go:171","msg":"trace[16176929] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-hqr5r; range_end:; response_count:1; response_revision:18282; }","duration":"393.598562ms","start":"2026-07-24T18:04:31.457822Z","end":"2026-07-24T18:04:31.851421Z","steps":["trace[16176929] 'agreement among raft nodes before linearized reading' (duration: 393.52074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.851436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.260954198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-24T18:04:31.851446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:31.457797Z","time spent":"393.645124ms","remote":"127.0.0.1:52912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9821,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-hqr5r\" "} {"level":"warn","ts":"2026-07-24T18:04:31.851455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.017316412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:04:31.851462Z","caller":"traceutil/trace.go:171","msg":"trace[426478737] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:18282; }","duration":"2.260984889s","start":"2026-07-24T18:04:29.590469Z","end":"2026-07-24T18:04:31.851454Z","steps":["trace[426478737] 'agreement among raft nodes before linearized reading' (duration: 2.260947248s)"],"step_count":1} {"level":"info","ts":"2026-07-24T18:04:31.85147Z","caller":"traceutil/trace.go:171","msg":"trace[1877847341] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:18282; }","duration":"3.017347913s","start":"2026-07-24T18:04:28.834118Z","end":"2026-07-24T18:04:31.851466Z","steps":["trace[1877847341] 'agreement among raft nodes before linearized reading' (duration: 3.017321612s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.851482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:28.834108Z","time spent":"3.017371503s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/heat-bootstrap\" "} {"level":"warn","ts":"2026-07-24T18:04:31.851482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:29.590466Z","time spent":"2.26101149s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/heat-ks-user\" "} {"level":"info","ts":"2026-07-24T18:04:31.851007Z","caller":"traceutil/trace.go:171","msg":"trace[1949872953] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:18282; }","duration":"2.260628219s","start":"2026-07-24T18:04:29.590375Z","end":"2026-07-24T18:04:31.851004Z","steps":["trace[1949872953] 'agreement among raft nodes before linearized reading' (duration: 2.260609498s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.851563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.017672572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:04:31.851575Z","caller":"traceutil/trace.go:171","msg":"trace[345610901] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:18282; }","duration":"3.017708963s","start":"2026-07-24T18:04:28.833862Z","end":"2026-07-24T18:04:31.851571Z","steps":["trace[345610901] 'agreement among raft nodes before linearized reading' (duration: 3.017686522s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.85158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:29.590362Z","time spent":"2.261211016s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" "} {"level":"warn","ts":"2026-07-24T18:04:31.851589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:28.833856Z","time spent":"3.017728044s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/heat-ks-user\" "} {"level":"warn","ts":"2026-07-24T18:04:31.851637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.017783355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:04:31.851648Z","caller":"traceutil/trace.go:171","msg":"trace[986296925] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:18282; }","duration":"3.017811636s","start":"2026-07-24T18:04:28.833832Z","end":"2026-07-24T18:04:31.851644Z","steps":["trace[986296925] 'agreement among raft nodes before linearized reading' (duration: 3.017791115s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.851659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:28.833827Z","time spent":"3.017829376s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"warn","ts":"2026-07-24T18:04:31.85057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.072551274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:04:31.851719Z","caller":"traceutil/trace.go:171","msg":"trace[737674170] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:18282; }","duration":"3.07379022s","start":"2026-07-24T18:04:28.77792Z","end":"2026-07-24T18:04:31.851711Z","steps":["trace[737674170] 'agreement among raft nodes before linearized reading' (duration: 3.072555124s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.851743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:28.777894Z","time spent":"3.07384111s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/heat-bootstrap\" "} {"level":"warn","ts":"2026-07-24T18:04:31.851742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.144948ms","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-07-24T18:04:31.851753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.01793178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:1 size:4959"} {"level":"info","ts":"2026-07-24T18:04:31.851768Z","caller":"traceutil/trace.go:171","msg":"trace[1432707328] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:1; response_revision:18282; }","duration":"3.01796071s","start":"2026-07-24T18:04:28.833803Z","end":"2026-07-24T18:04:31.851764Z","steps":["trace[1432707328] 'agreement among raft nodes before linearized reading' (duration: 3.017915999s)"],"step_count":1} {"level":"info","ts":"2026-07-24T18:04:31.851768Z","caller":"traceutil/trace.go:171","msg":"trace[448434370] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18282; }","duration":"393.19532ms","start":"2026-07-24T18:04:31.458563Z","end":"2026-07-24T18:04:31.851759Z","steps":["trace[448434370] 'agreement among raft nodes before linearized reading' (duration: 393.118288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.851784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:28.833797Z","time spent":"3.017982801s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4981,"request content":"key:\"/registry/jobs/openstack/heat-rabbit-init\" "} {"level":"warn","ts":"2026-07-24T18:04:31.851788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:31.458559Z","time spent":"393.224681ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-24T18:04:31.851842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.018051402s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:1 size:5129"} {"level":"info","ts":"2026-07-24T18:04:31.851865Z","caller":"traceutil/trace.go:171","msg":"trace[1242982397] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:1; response_revision:18282; }","duration":"3.018089134s","start":"2026-07-24T18:04:28.833772Z","end":"2026-07-24T18:04:31.851861Z","steps":["trace[1242982397] 'agreement among raft nodes before linearized reading' (duration: 3.018044033s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.851873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.531229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-24T18:04:31.851892Z","caller":"traceutil/trace.go:171","msg":"trace[1333962062] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18282; }","duration":"393.563961ms","start":"2026-07-24T18:04:31.458323Z","end":"2026-07-24T18:04:31.851887Z","steps":["trace[1333962062] 'agreement among raft nodes before linearized reading' (duration: 393.506909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.851896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.018138835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:04:31.851909Z","caller":"traceutil/trace.go:171","msg":"trace[1519789536] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:18282; }","duration":"3.018179846s","start":"2026-07-24T18:04:28.833725Z","end":"2026-07-24T18:04:31.851905Z","steps":["trace[1519789536] 'agreement among raft nodes before linearized reading' (duration: 3.018159396s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.85191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:31.458317Z","time spent":"393.588561ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T18:04:31.851921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:28.833719Z","time spent":"3.018199617s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" "} {"level":"warn","ts":"2026-07-24T18:04:31.851964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.018252089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:04:31.851974Z","caller":"traceutil/trace.go:171","msg":"trace[45140189] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:18282; }","duration":"3.018281219s","start":"2026-07-24T18:04:28.833689Z","end":"2026-07-24T18:04:31.85197Z","steps":["trace[45140189] 'agreement among raft nodes before linearized reading' (duration: 3.018263529s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.851985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:28.833681Z","time spent":"3.01830145s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/heat-ks-user\" "} {"level":"info","ts":"2026-07-24T18:04:31.850609Z","caller":"traceutil/trace.go:171","msg":"trace[2073672704] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:18282; }","duration":"3.072558864s","start":"2026-07-24T18:04:28.778044Z","end":"2026-07-24T18:04:31.850603Z","steps":["trace[2073672704] 'agreement among raft nodes before linearized reading' (duration: 3.072513093s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.851877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:28.833766Z","time spent":"3.018108124s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5151,"request content":"key:\"/registry/jobs/openstack/heat-db-sync\" "} {"level":"warn","ts":"2026-07-24T18:04:31.85207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:28.778038Z","time spent":"3.074016525s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"warn","ts":"2026-07-24T18:04:31.852027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.791317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-24T18:04:31.852152Z","caller":"traceutil/trace.go:171","msg":"trace[262016503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18282; }","duration":"393.929621ms","start":"2026-07-24T18:04:31.458211Z","end":"2026-07-24T18:04:31.85214Z","steps":["trace[262016503] 'agreement among raft nodes before linearized reading' (duration: 393.764586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.852185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:31.458203Z","time spent":"393.971202ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T18:04:31.852204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.018585018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:1 size:5129"} {"level":"info","ts":"2026-07-24T18:04:31.85222Z","caller":"traceutil/trace.go:171","msg":"trace[1034891586] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:1; response_revision:18282; }","duration":"3.01865962s","start":"2026-07-24T18:04:28.833555Z","end":"2026-07-24T18:04:31.852215Z","steps":["trace[1034891586] 'agreement among raft nodes before linearized reading' (duration: 3.018585558s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.852232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:28.833526Z","time spent":"3.018703481s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5151,"request content":"key:\"/registry/jobs/openstack/heat-db-sync\" "} {"level":"warn","ts":"2026-07-24T18:04:31.852293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.074186291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:1 size:4959"} {"level":"warn","ts":"2026-07-24T18:04:31.850683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:29.590442Z","time spent":"2.260230097s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/heat-bootstrap\" "} {"level":"info","ts":"2026-07-24T18:04:31.852336Z","caller":"traceutil/trace.go:171","msg":"trace[400345809] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:1; response_revision:18282; }","duration":"3.074233722s","start":"2026-07-24T18:04:28.778084Z","end":"2026-07-24T18:04:31.852318Z","steps":["trace[400345809] 'agreement among raft nodes before linearized reading' (duration: 3.074144219s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:04:31.852364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:04:28.778079Z","time spent":"3.074278403s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4981,"request content":"key:\"/registry/jobs/openstack/heat-rabbit-init\" "} {"level":"info","ts":"2026-07-24T18:07:01.934231Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4da37caf00b87dfe","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T18:07:01.949122Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-24T18:07:01.949297Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-24T18:08:28.875815Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17591} {"level":"info","ts":"2026-07-24T18:08:28.977864Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17591,"took":"97.573582ms","hash":3973245966,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":34467840,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-24T18:08:28.977954Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3973245966,"revision":17591,"compact-revision":15296} {"level":"info","ts":"2026-07-24T18:09:25.472718Z","caller":"traceutil/trace.go:171","msg":"trace[2113693880] transaction","detail":"{read_only:false; response_revision:20360; number_of_response:1; }","duration":"193.458794ms","start":"2026-07-24T18:09:25.279235Z","end":"2026-07-24T18:09:25.472694Z","steps":["trace[2113693880] 'process raft request' (duration: 95.842351ms)","trace[2113693880] 'compare' (duration: 97.467148ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T18:09:25.473004Z","caller":"traceutil/trace.go:171","msg":"trace[1345603605] transaction","detail":"{read_only:false; response_revision:20361; number_of_response:1; }","duration":"192.595479ms","start":"2026-07-24T18:09:25.280359Z","end":"2026-07-24T18:09:25.472955Z","steps":["trace[1345603605] 'process raft request' (duration: 192.29408ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T18:09:25.967823Z","caller":"traceutil/trace.go:171","msg":"trace[668876292] transaction","detail":"{read_only:false; response_revision:20362; number_of_response:1; }","duration":"278.998727ms","start":"2026-07-24T18:09:25.688792Z","end":"2026-07-24T18:09:25.96779Z","steps":["trace[668876292] 'process raft request' (duration: 278.809091ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T18:09:25.983962Z","caller":"traceutil/trace.go:171","msg":"trace[61385515] linearizableReadLoop","detail":"{readStateIndex:21045; appliedIndex:21042; }","duration":"140.38404ms","start":"2026-07-24T18:09:25.843538Z","end":"2026-07-24T18:09:25.983922Z","steps":["trace[61385515] 'read index received' (duration: 123.867162ms)","trace[61385515] 'applied index is now lower than readState.Index' (duration: 16.515358ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T18:09:25.984113Z","caller":"traceutil/trace.go:171","msg":"trace[1638615993] transaction","detail":"{read_only:false; response_revision:20364; number_of_response:1; }","duration":"294.440023ms","start":"2026-07-24T18:09:25.689648Z","end":"2026-07-24T18:09:25.984088Z","steps":["trace[1638615993] 'process raft request' (duration: 294.221477ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T18:09:25.983988Z","caller":"traceutil/trace.go:171","msg":"trace[353567844] transaction","detail":"{read_only:false; response_revision:20363; number_of_response:1; }","duration":"294.341812ms","start":"2026-07-24T18:09:25.689608Z","end":"2026-07-24T18:09:25.98395Z","steps":["trace[353567844] 'process raft request' (duration: 294.060763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:09:25.984477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.926455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-24T18:09:25.984525Z","caller":"traceutil/trace.go:171","msg":"trace[1355572411] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20364; }","duration":"141.038079ms","start":"2026-07-24T18:09:25.843471Z","end":"2026-07-24T18:09:25.98451Z","steps":["trace[1355572411] 'agreement among raft nodes before linearized reading' (duration: 140.703569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:09:25.984875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.695734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-24T18:09:25.984917Z","caller":"traceutil/trace.go:171","msg":"trace[208167289] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20364; }","duration":"135.744305ms","start":"2026-07-24T18:09:25.849156Z","end":"2026-07-24T18:09:25.984901Z","steps":["trace[208167289] 'agreement among raft nodes before linearized reading' (duration: 135.651692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:09:25.985435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.591499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-24T18:09:25.985574Z","caller":"traceutil/trace.go:171","msg":"trace[861283064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20364; }","duration":"137.698421ms","start":"2026-07-24T18:09:25.847785Z","end":"2026-07-24T18:09:25.985483Z","steps":["trace[861283064] 'agreement among raft nodes before linearized reading' (duration: 136.908048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:09:26.28662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.489104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T18:09:26.286881Z","caller":"traceutil/trace.go:171","msg":"trace[649755438] transaction","detail":"{read_only:false; response_revision:20365; number_of_response:1; }","duration":"296.921666ms","start":"2026-07-24T18:09:25.989926Z","end":"2026-07-24T18:09:26.286848Z","steps":["trace[649755438] 'process raft request' (duration: 130.098133ms)","trace[649755438] 'compare' (duration: 166.322989ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T18:09:26.286994Z","caller":"traceutil/trace.go:171","msg":"trace[928613879] transaction","detail":"{read_only:false; response_revision:20366; number_of_response:1; }","duration":"296.674838ms","start":"2026-07-24T18:09:25.9903Z","end":"2026-07-24T18:09:26.286975Z","steps":["trace[928613879] 'process raft request' (duration: 296.568035ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T18:09:26.287157Z","caller":"traceutil/trace.go:171","msg":"trace[1618226696] linearizableReadLoop","detail":"{readStateIndex:21048; appliedIndex:21045; }","duration":"261.780969ms","start":"2026-07-24T18:09:26.025349Z","end":"2026-07-24T18:09:26.28713Z","steps":["trace[1618226696] 'read index received' (duration: 94.77611ms)","trace[1618226696] 'applied index is now lower than readState.Index' (duration: 167.002309ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T18:09:26.287221Z","caller":"traceutil/trace.go:171","msg":"trace[828128582] transaction","detail":"{read_only:false; response_revision:20367; number_of_response:1; }","duration":"296.473642ms","start":"2026-07-24T18:09:25.990712Z","end":"2026-07-24T18:09:26.287186Z","steps":["trace[828128582] 'process raft request' (duration: 296.226345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:09:26.28739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.010056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:09:26.287487Z","caller":"traceutil/trace.go:171","msg":"trace[1556127689] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:20367; }","duration":"262.135049ms","start":"2026-07-24T18:09:26.025331Z","end":"2026-07-24T18:09:26.287466Z","steps":["trace[1556127689] 'agreement among raft nodes before linearized reading' (duration: 261.918963ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T18:10:32.801017Z","caller":"traceutil/trace.go:171","msg":"trace[1962395219] transaction","detail":"{read_only:false; response_revision:21008; number_of_response:1; }","duration":"113.23219ms","start":"2026-07-24T18:10:32.687764Z","end":"2026-07-24T18:10:32.800996Z","steps":["trace[1962395219] 'process raft request' (duration: 113.098245ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T18:10:32.846907Z","caller":"traceutil/trace.go:171","msg":"trace[1347137014] linearizableReadLoop","detail":"{readStateIndex:21717; appliedIndex:21715; }","duration":"152.538088ms","start":"2026-07-24T18:10:32.694323Z","end":"2026-07-24T18:10:32.846861Z","steps":["trace[1347137014] 'read index received' (duration: 106.616108ms)","trace[1347137014] 'applied index is now lower than readState.Index' (duration: 45.92101ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T18:10:32.846994Z","caller":"traceutil/trace.go:171","msg":"trace[1818545764] transaction","detail":"{read_only:false; response_revision:21009; number_of_response:1; }","duration":"158.642396ms","start":"2026-07-24T18:10:32.688333Z","end":"2026-07-24T18:10:32.846975Z","steps":["trace[1818545764] 'process raft request' (duration: 158.378038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:10:32.847091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.744314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:10:32.847135Z","caller":"traceutil/trace.go:171","msg":"trace[1132476733] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:0; response_revision:21009; }","duration":"152.826497ms","start":"2026-07-24T18:10:32.694299Z","end":"2026-07-24T18:10:32.847126Z","steps":["trace[1132476733] 'agreement among raft nodes before linearized reading' (duration: 152.733904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:10:32.847175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.476085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:10:32.847233Z","caller":"traceutil/trace.go:171","msg":"trace[1870891971] range","detail":"{range_begin:/registry/jobs/openstack/octavia-rabbit-init; range_end:; response_count:0; response_revision:21009; }","duration":"152.586469ms","start":"2026-07-24T18:10:32.694632Z","end":"2026-07-24T18:10:32.847218Z","steps":["trace[1870891971] 'agreement among raft nodes before linearized reading' (duration: 152.467445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:10:32.847262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.311901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:10:32.847367Z","caller":"traceutil/trace.go:171","msg":"trace[1354071728] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:21009; }","duration":"152.458935ms","start":"2026-07-24T18:10:32.694887Z","end":"2026-07-24T18:10:32.847346Z","steps":["trace[1354071728] 'agreement among raft nodes before linearized reading' (duration: 152.28091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:10:32.847442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.678662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-db-sync\" ","response":"range_response_count:1 size:5140"} {"level":"info","ts":"2026-07-24T18:10:32.847558Z","caller":"traceutil/trace.go:171","msg":"trace[1082958368] range","detail":"{range_begin:/registry/jobs/openstack/octavia-db-sync; range_end:; response_count:1; response_revision:21009; }","duration":"152.843407ms","start":"2026-07-24T18:10:32.694689Z","end":"2026-07-24T18:10:32.847532Z","steps":["trace[1082958368] 'agreement among raft nodes before linearized reading' (duration: 152.512247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:11:00.345293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869361860345570,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T18:11:00.845956Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.126341284s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-24T18:11:00.846853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.925394978s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:11:00.846973Z","caller":"traceutil/trace.go:171","msg":"trace[522135716] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:21192; }","duration":"1.925566442s","start":"2026-07-24T18:10:58.921381Z","end":"2026-07-24T18:11:00.846947Z","steps":["trace[522135716] 'range keys from in-memory index tree' (duration: 1.925265193s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:11:00.84702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:10:58.921355Z","time spent":"1.925656074s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/octavia-ks-user\" "} {"level":"warn","ts":"2026-07-24T18:11:00.847308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.925817529s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:11:00.847355Z","caller":"traceutil/trace.go:171","msg":"trace[648701608] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:0; response_revision:21192; }","duration":"1.925908522s","start":"2026-07-24T18:10:58.92143Z","end":"2026-07-24T18:11:00.847339Z","steps":["trace[648701608] 'range keys from in-memory index tree' (duration: 1.925699766s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:11:00.847409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:10:58.921404Z","time spent":"1.925993194s","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" "} {"level":"warn","ts":"2026-07-24T18:11:00.847726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.901542687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-24T18:11:00.847776Z","caller":"traceutil/trace.go:171","msg":"trace[95904322] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21192; }","duration":"1.901636139s","start":"2026-07-24T18:10:58.94612Z","end":"2026-07-24T18:11:00.847756Z","steps":["trace[95904322] 'range keys from in-memory index tree' (duration: 1.901341531s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:11:00.847816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:10:58.946092Z","time spent":"1.901713272s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-24T18:11:00.848048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.877752558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:11:00.848133Z","caller":"traceutil/trace.go:171","msg":"trace[319409420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21192; }","duration":"1.87783302s","start":"2026-07-24T18:10:58.970241Z","end":"2026-07-24T18:11:00.848074Z","steps":["trace[319409420] 'range keys from in-memory index tree' (duration: 1.877660914s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:11:00.84817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:10:58.970217Z","time spent":"1.877943763s","remote":"127.0.0.1:52734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T18:11:00.848693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.850684174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-24T18:11:00.848729Z","caller":"traceutil/trace.go:171","msg":"trace[275980799] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21192; }","duration":"1.850721755s","start":"2026-07-24T18:10:58.997995Z","end":"2026-07-24T18:11:00.848717Z","steps":["trace[275980799] 'range keys from in-memory index tree' (duration: 1.850485338s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:11:00.848757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:10:58.997913Z","time spent":"1.850837858s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-24T18:11:00.85025Z","caller":"traceutil/trace.go:171","msg":"trace[1628409875] transaction","detail":"{read_only:false; response_revision:21193; number_of_response:1; }","duration":"1.130784493s","start":"2026-07-24T18:10:59.719435Z","end":"2026-07-24T18:11:00.85022Z","steps":["trace[1628409875] 'process raft request' (duration: 1.126905321s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:11:00.850513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:10:59.719405Z","time spent":"1.13101159s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T18:11:00.853565Z","caller":"traceutil/trace.go:171","msg":"trace[1280328341] linearizableReadLoop","detail":"{readStateIndex:21908; appliedIndex:21906; }","duration":"1.00916827s","start":"2026-07-24T18:10:59.844366Z","end":"2026-07-24T18:11:00.853534Z","steps":["trace[1280328341] 'read index received' (duration: 1.0046737s)","trace[1280328341] 'applied index is now lower than readState.Index' (duration: 4.49291ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T18:11:00.853911Z","caller":"traceutil/trace.go:171","msg":"trace[1443199176] transaction","detail":"{read_only:false; response_revision:21194; number_of_response:1; }","duration":"1.005458671s","start":"2026-07-24T18:10:59.848439Z","end":"2026-07-24T18:11:00.853897Z","steps":["trace[1443199176] 'process raft request' (duration: 1.004839853s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:11:00.854044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:10:59.848417Z","time spent":"1.005527924s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T18:11:00.854279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.617725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:11:00.854315Z","caller":"traceutil/trace.go:171","msg":"trace[2016962036] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:21196; }","duration":"497.693817ms","start":"2026-07-24T18:11:00.356609Z","end":"2026-07-24T18:11:00.854303Z","steps":["trace[2016962036] 'agreement among raft nodes before linearized reading' (duration: 497.617235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:11:00.854345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:11:00.356587Z","time spent":"497.750129ms","remote":"127.0.0.1:53036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-24T18:11:00.854341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009925592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:11:00.854464Z","caller":"traceutil/trace.go:171","msg":"trace[200343540] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:21196; }","duration":"1.010123827s","start":"2026-07-24T18:10:59.844305Z","end":"2026-07-24T18:11:00.854429Z","steps":["trace[200343540] 'agreement among raft nodes before linearized reading' (duration: 1.009887061s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:11:00.854509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:10:59.844277Z","time spent":"1.01022265s","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-07-24T18:11:00.854754Z","caller":"traceutil/trace.go:171","msg":"trace[1880948038] transaction","detail":"{read_only:false; response_revision:21195; number_of_response:1; }","duration":"498.256883ms","start":"2026-07-24T18:11:00.356486Z","end":"2026-07-24T18:11:00.854743Z","steps":["trace[1880948038] 'process raft request' (duration: 496.961565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:11:00.854894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:11:00.356457Z","time spent":"498.362826ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T18:11:00.855029Z","caller":"traceutil/trace.go:171","msg":"trace[1410244874] transaction","detail":"{read_only:false; response_revision:21196; number_of_response:1; }","duration":"498.360296ms","start":"2026-07-24T18:11:00.356659Z","end":"2026-07-24T18:11:00.855019Z","steps":["trace[1410244874] 'process raft request' (duration: 496.828831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:11:00.855066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:11:00.356653Z","time spent":"498.390297ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T18:13:14.533179Z","caller":"traceutil/trace.go:171","msg":"trace[142556452] linearizableReadLoop","detail":"{readStateIndex:23008; appliedIndex:23006; }","duration":"104.192948ms","start":"2026-07-24T18:13:14.42894Z","end":"2026-07-24T18:13:14.533133Z","steps":["trace[142556452] 'read index received' (duration: 23.353816ms)","trace[142556452] 'applied index is now lower than readState.Index' (duration: 80.836202ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T18:13:14.53321Z","caller":"traceutil/trace.go:171","msg":"trace[644507468] transaction","detail":"{read_only:false; response_revision:22262; number_of_response:1; }","duration":"118.361237ms","start":"2026-07-24T18:13:14.414795Z","end":"2026-07-24T18:13:14.533156Z","steps":["trace[644507468] 'process raft request' (duration: 65.976441ms)","trace[644507468] 'compare' (duration: 51.751658ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T18:13:14.533493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.522337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/cert-manager-test/test-selfsigned\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T18:13:14.533591Z","caller":"traceutil/trace.go:171","msg":"trace[1579653018] range","detail":"{range_begin:/registry/cert-manager.io/issuers/cert-manager-test/test-selfsigned; range_end:; response_count:0; response_revision:22262; }","duration":"104.686401ms","start":"2026-07-24T18:13:14.428887Z","end":"2026-07-24T18:13:14.533573Z","steps":["trace[1579653018] 'agreement among raft nodes before linearized reading' (duration: 104.335601ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T18:13:28.884486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20066} {"level":"info","ts":"2026-07-24T18:13:29.010342Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20066,"took":"120.14332ms","hash":988523571,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":42000384,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-07-24T18:13:29.010441Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":988523571,"revision":20066,"compact-revision":17591} {"level":"info","ts":"2026-07-24T18:13:30.143173Z","caller":"traceutil/trace.go:171","msg":"trace[1799004660] transaction","detail":"{read_only:false; response_revision:22844; number_of_response:1; }","duration":"117.207776ms","start":"2026-07-24T18:13:30.025932Z","end":"2026-07-24T18:13:30.14314Z","steps":["trace[1799004660] 'process raft request' (duration: 117.011919ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T18:13:30.341384Z","caller":"traceutil/trace.go:171","msg":"trace[1868021686] linearizableReadLoop","detail":"{readStateIndex:23606; appliedIndex:23603; }","duration":"291.634867ms","start":"2026-07-24T18:13:30.049724Z","end":"2026-07-24T18:13:30.341358Z","steps":["trace[1868021686] 'read index received' (duration: 93.139128ms)","trace[1868021686] 'applied index is now lower than readState.Index' (duration: 198.494479ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T18:13:30.341439Z","caller":"traceutil/trace.go:171","msg":"trace[945946686] transaction","detail":"{read_only:false; response_revision:22845; number_of_response:1; }","duration":"315.067655ms","start":"2026-07-24T18:13:30.026343Z","end":"2026-07-24T18:13:30.34141Z","steps":["trace[945946686] 'process raft request' (duration: 314.764167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:13:30.341579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:13:30.026337Z","time spent":"315.165228ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T18:13:30.341502Z","caller":"traceutil/trace.go:171","msg":"trace[1907112794] transaction","detail":"{read_only:false; response_revision:22846; number_of_response:1; }","duration":"311.128182ms","start":"2026-07-24T18:13:30.030363Z","end":"2026-07-24T18:13:30.341491Z","steps":["trace[1907112794] 'process raft request' (duration: 310.907605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:13:30.341905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T18:13:30.030355Z","time spent":"311.369158ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T18:13:30.341978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.164026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-24T18:13:30.342022Z","caller":"traceutil/trace.go:171","msg":"trace[1575420029] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22846; }","duration":"289.246539ms","start":"2026-07-24T18:13:30.052765Z","end":"2026-07-24T18:13:30.342011Z","steps":["trace[1575420029] 'agreement among raft nodes before linearized reading' (duration: 289.117444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:13:30.342079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.322947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-24T18:13:30.34216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.871757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-07-24T18:13:30.342182Z","caller":"traceutil/trace.go:171","msg":"trace[1489387438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22846; }","duration":"292.475132ms","start":"2026-07-24T18:13:30.04968Z","end":"2026-07-24T18:13:30.342155Z","steps":["trace[1489387438] 'agreement among raft nodes before linearized reading' (duration: 292.207193ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T18:13:30.342184Z","caller":"traceutil/trace.go:171","msg":"trace[193945966] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:22846; }","duration":"124.935158ms","start":"2026-07-24T18:13:30.217241Z","end":"2026-07-24T18:13:30.342177Z","steps":["trace[193945966] 'agreement among raft nodes before linearized reading' (duration: 124.853376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T18:13:30.342397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.087916ms","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-07-24T18:13:30.34245Z","caller":"traceutil/trace.go:171","msg":"trace[7007124] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:22846; }","duration":"160.142888ms","start":"2026-07-24T18:13:30.182295Z","end":"2026-07-24T18:13:30.342437Z","steps":["trace[7007124] 'agreement among raft nodes before linearized reading' (duration: 159.838519ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T18:18:28.89355Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22821} {"level":"info","ts":"2026-07-24T18:18:29.031794Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22821,"took":"132.98907ms","hash":1173443178,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":33296384,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-24T18:18:29.031882Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1173443178,"revision":22821,"compact-revision":20066} {"level":"info","ts":"2026-07-24T18:23:28.901903Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25394} {"level":"info","ts":"2026-07-24T18:23:29.029442Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25394,"took":"124.489493ms","hash":419955124,"current-db-size-bytes":58097664,"current-db-size":"58 MB","current-db-size-in-use-bytes":29544448,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-24T18:23:29.029503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":419955124,"revision":25394,"compact-revision":22821}