{"level":"warn","ts":"2026-04-11T17:42:40.282674Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-11T17:42:40.282843Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.200: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.200:2380","--initial-cluster=instance=https://199.19.213.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.200: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-04-11T17:42:40.282952Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-11T17:42:40.282971Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-04-11T17:42:40.283Z","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-04-11T17:42:40.284148Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"]} {"level":"info","ts":"2026-04-11T17:42:40.284286Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.200: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-04-11T17:42:40.289703Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.132902ms"} {"level":"info","ts":"2026-04-11T17:42:40.296019Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3363378c2227fa42","cluster-id":"7faf957b2e4cf761"} {"level":"info","ts":"2026-04-11T17:42:40.296101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=()"} {"level":"info","ts":"2026-04-11T17:42:40.29613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 0"} {"level":"info","ts":"2026-04-11T17:42:40.296148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3363378c2227fa42 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-11T17:42:40.296165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became follower at term 1"} {"level":"info","ts":"2026-04-11T17:42:40.296216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"warn","ts":"2026-04-11T17:42:40.306547Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-11T17:42:40.309946Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-11T17:42:40.312327Z","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-04-11T17:42:40.316673Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3363378c2227fa42","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-11T17:42:40.317576Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3363378c2227fa42","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-11T17:42:40.31768Z","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-04-11T17:42:40.317774Z","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-04-11T17:42:40.317786Z","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-04-11T17:42:40.318644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 switched to configuration voters=(3702864393636674114)"} {"level":"info","ts":"2026-04-11T17:42:40.318909Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","added-peer-id":"3363378c2227fa42","added-peer-peer-urls":["https://199.19.213.200:2380"]} {"level":"info","ts":"2026-04-11T17:42:40.32115Z","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-04-11T17:42:40.32131Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-04-11T17:42:40.321338Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.200:2380"} {"level":"info","ts":"2026-04-11T17:42:40.321346Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3363378c2227fa42","initial-advertise-peer-urls":["https://199.19.213.200:2380"],"listen-peer-urls":["https://199.19.213.200:2380"],"advertise-client-urls":["https://199.19.213.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-11T17:42:40.321392Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-11T17:42:41.196835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 is starting a new election at term 1"} {"level":"info","ts":"2026-04-11T17:42:41.196912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-11T17:42:41.196943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgPreVoteResp from 3363378c2227fa42 at term 1"} {"level":"info","ts":"2026-04-11T17:42:41.19696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became candidate at term 2"} {"level":"info","ts":"2026-04-11T17:42:41.196968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 received MsgVoteResp from 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-04-11T17:42:41.196983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3363378c2227fa42 became leader at term 2"} {"level":"info","ts":"2026-04-11T17:42:41.196997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3363378c2227fa42 elected leader 3363378c2227fa42 at term 2"} {"level":"info","ts":"2026-04-11T17:42:41.198192Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3363378c2227fa42","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.200:2379]}","request-path":"/0/members/3363378c2227fa42/attributes","cluster-id":"7faf957b2e4cf761","publish-timeout":"7s"} {"level":"info","ts":"2026-04-11T17:42:41.19832Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:42:41.198643Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:42:41.198624Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:42:41.199714Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-11T17:42:41.199746Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-11T17:42:41.199857Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7faf957b2e4cf761","local-member-id":"3363378c2227fa42","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:42:41.200967Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:42:41.201064Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:42:41.203531Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-11T17:42:41.205425Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.200:2379"} {"level":"info","ts":"2026-04-11T17:42:52.543311Z","caller":"traceutil/trace.go:171","msg":"trace[67938460] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"134.771301ms","start":"2026-04-11T17:42:52.408493Z","end":"2026-04-11T17:42:52.543264Z","steps":["trace[67938460] 'process raft request' (duration: 134.530389ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:42:58.349991Z","caller":"traceutil/trace.go:171","msg":"trace[1987286317] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"198.602366ms","start":"2026-04-11T17:42:58.151363Z","end":"2026-04-11T17:42:58.349965Z","steps":["trace[1987286317] 'process raft request' (duration: 198.483987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:58.818844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.443709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:42:58.818962Z","caller":"traceutil/trace.go:171","msg":"trace[40260839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:442; }","duration":"323.654396ms","start":"2026-04-11T17:42:58.495283Z","end":"2026-04-11T17:42:58.818938Z","steps":["trace[40260839] 'range keys from in-memory index tree' (duration: 323.376564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:42:58.819009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:42:58.495267Z","time spent":"323.731325ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-11T17:42:58.819077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.299119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-11T17:42:58.819169Z","caller":"traceutil/trace.go:171","msg":"trace[922349628] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:442; }","duration":"256.43137ms","start":"2026-04-11T17:42:58.562721Z","end":"2026-04-11T17:42:58.819152Z","steps":["trace[922349628] 'range keys from in-memory index tree' (duration: 256.233634ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:07.375705Z","caller":"traceutil/trace.go:171","msg":"trace[1687919392] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"169.641172ms","start":"2026-04-11T17:43:07.205998Z","end":"2026-04-11T17:43:07.375639Z","steps":["trace[1687919392] 'process raft request' (duration: 169.190978ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:09.143414Z","caller":"traceutil/trace.go:171","msg":"trace[696856260] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"116.70485ms","start":"2026-04-11T17:43:09.026679Z","end":"2026-04-11T17:43:09.143384Z","steps":["trace[696856260] 'process raft request' (duration: 116.582319ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:09.151446Z","caller":"traceutil/trace.go:171","msg":"trace[1143601381] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"122.325519ms","start":"2026-04-11T17:43:09.029097Z","end":"2026-04-11T17:43:09.151422Z","steps":["trace[1143601381] 'process raft request' (duration: 122.02829ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:09.151675Z","caller":"traceutil/trace.go:171","msg":"trace[453605006] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"122.390038ms","start":"2026-04-11T17:43:09.029271Z","end":"2026-04-11T17:43:09.151661Z","steps":["trace[453605006] 'process raft request' (duration: 121.998686ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:09.151829Z","caller":"traceutil/trace.go:171","msg":"trace[1087350122] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"112.664039ms","start":"2026-04-11T17:43:09.039147Z","end":"2026-04-11T17:43:09.151811Z","steps":["trace[1087350122] 'process raft request' (duration: 112.459347ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:09.151899Z","caller":"traceutil/trace.go:171","msg":"trace[570349552] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"110.6315ms","start":"2026-04-11T17:43:09.041254Z","end":"2026-04-11T17:43:09.151885Z","steps":["trace[570349552] 'process raft request' (duration: 110.46277ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:09.152016Z","caller":"traceutil/trace.go:171","msg":"trace[612045585] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"110.791972ms","start":"2026-04-11T17:43:09.041216Z","end":"2026-04-11T17:43:09.152008Z","steps":["trace[612045585] 'process raft request' (duration: 110.454933ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:09.152127Z","caller":"traceutil/trace.go:171","msg":"trace[832748522] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"101.556049ms","start":"2026-04-11T17:43:09.050565Z","end":"2026-04-11T17:43:09.152121Z","steps":["trace[832748522] 'process raft request' (duration: 101.230354ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:09.152209Z","caller":"traceutil/trace.go:171","msg":"trace[422718323] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"109.939968ms","start":"2026-04-11T17:43:09.042263Z","end":"2026-04-11T17:43:09.152203Z","steps":["trace[422718323] 'process raft request' (duration: 109.497399ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:15.202889Z","caller":"traceutil/trace.go:171","msg":"trace[706960480] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:625; }","duration":"270.823272ms","start":"2026-04-11T17:43:14.93203Z","end":"2026-04-11T17:43:15.202854Z","steps":["trace[706960480] 'read index received' (duration: 28.000108ms)","trace[706960480] 'applied index is now lower than readState.Index' (duration: 242.822045ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:43:15.203078Z","caller":"traceutil/trace.go:171","msg":"trace[746383097] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"277.57838ms","start":"2026-04-11T17:43:14.925485Z","end":"2026-04-11T17:43:15.203063Z","steps":["trace[746383097] 'process raft request' (duration: 276.578156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:43:15.203293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.256816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8qh27\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-11T17:43:15.203322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.269417ms","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-04-11T17:43:15.203393Z","caller":"traceutil/trace.go:171","msg":"trace[236229346] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8qh27; range_end:; response_count:1; response_revision:609; }","duration":"117.429614ms","start":"2026-04-11T17:43:15.08594Z","end":"2026-04-11T17:43:15.20337Z","steps":["trace[236229346] 'agreement among raft nodes before linearized reading' (duration: 117.210475ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:15.203411Z","caller":"traceutil/trace.go:171","msg":"trace[454454254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"271.413287ms","start":"2026-04-11T17:43:14.931972Z","end":"2026-04-11T17:43:15.203386Z","steps":["trace[454454254] 'agreement among raft nodes before linearized reading' (duration: 271.218588ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:43:15.328833Z","caller":"traceutil/trace.go:171","msg":"trace[1247028095] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"116.712384ms","start":"2026-04-11T17:43:15.21209Z","end":"2026-04-11T17:43:15.328803Z","steps":["trace[1247028095] 'process raft request' (duration: 75.797374ms)","trace[1247028095] 'compare' (duration: 40.743011ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:43:15.811067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.212694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:43:15.811512Z","caller":"traceutil/trace.go:171","msg":"trace[1437083897] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:629; }","duration":"314.425629ms","start":"2026-04-11T17:43:15.497061Z","end":"2026-04-11T17:43:15.811486Z","steps":["trace[1437083897] 'read index received' (duration: 69.451µs)","trace[1437083897] 'applied index is now lower than readState.Index' (duration: 314.354519ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:43:15.811657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.601397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:43:15.811697Z","caller":"traceutil/trace.go:171","msg":"trace[1319272618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"314.697777ms","start":"2026-04-11T17:43:15.496986Z","end":"2026-04-11T17:43:15.811684Z","steps":["trace[1319272618] 'agreement among raft nodes before linearized reading' (duration: 314.585494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:43:15.811745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:43:15.496956Z","time spent":"314.778743ms","remote":"127.0.0.1:51578","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-04-11T17:43:15.812179Z","caller":"traceutil/trace.go:171","msg":"trace[1427315520] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"589.406178ms","start":"2026-04-11T17:43:15.222729Z","end":"2026-04-11T17:43:15.812135Z","steps":["trace[1427315520] 'process raft request' (duration: 260.67664ms)","trace[1427315520] 'compare' (duration: 325.983838ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:43:15.812244Z","caller":"traceutil/trace.go:171","msg":"trace[1627975814] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"549.654627ms","start":"2026-04-11T17:43:15.262558Z","end":"2026-04-11T17:43:15.812213Z","steps":["trace[1627975814] 'process raft request' (duration: 548.66089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:43:15.812374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:43:15.222707Z","time spent":"589.57169ms","remote":"127.0.0.1:51838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:43:15.812573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:43:15.262503Z","time spent":"549.878184ms","remote":"127.0.0.1:52134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:43:17.329901Z","caller":"traceutil/trace.go:171","msg":"trace[1573301164] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"103.084007ms","start":"2026-04-11T17:43:17.226796Z","end":"2026-04-11T17:43:17.32988Z","steps":["trace[1573301164] 'process raft request' (duration: 102.936786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:43:31.901762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.28992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:43:31.901881Z","caller":"traceutil/trace.go:171","msg":"trace[1011197534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"405.460872ms","start":"2026-04-11T17:43:31.496401Z","end":"2026-04-11T17:43:31.901862Z","steps":["trace[1011197534] 'range keys from in-memory index tree' (duration: 405.01029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:43:31.901984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.589037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-11T17:43:31.902091Z","caller":"traceutil/trace.go:171","msg":"trace[160479965] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:777; }","duration":"264.711859ms","start":"2026-04-11T17:43:31.637355Z","end":"2026-04-11T17:43:31.902066Z","steps":["trace[160479965] 'range keys from in-memory index tree' (duration: 264.358075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:43:31.902081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:43:31.496384Z","time spent":"405.530214ms","remote":"127.0.0.1:51578","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-04-11T17:43:32.013319Z","caller":"traceutil/trace.go:171","msg":"trace[871646527] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"106.832932ms","start":"2026-04-11T17:43:31.906463Z","end":"2026-04-11T17:43:32.013296Z","steps":["trace[871646527] 'process raft request' (duration: 106.687318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:44:43.886159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.846635ms","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-04-11T17:44:43.886214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.4164ms","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-04-11T17:44:43.886297Z","caller":"traceutil/trace.go:171","msg":"trace[1858805761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1815; }","duration":"129.544548ms","start":"2026-04-11T17:44:43.756719Z","end":"2026-04-11T17:44:43.886263Z","steps":["trace[1858805761] 'range keys from in-memory index tree' (duration: 129.240581ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:44:43.88631Z","caller":"traceutil/trace.go:171","msg":"trace[1938591807] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1815; }","duration":"134.993039ms","start":"2026-04-11T17:44:43.751239Z","end":"2026-04-11T17:44:43.886232Z","steps":["trace[1938591807] 'range keys from in-memory index tree' (duration: 134.638431ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:44:48.85268Z","caller":"traceutil/trace.go:171","msg":"trace[1439253908] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1938; }","duration":"127.789418ms","start":"2026-04-11T17:44:48.724866Z","end":"2026-04-11T17:44:48.852655Z","steps":["trace[1439253908] 'read index received' (duration: 110.530752ms)","trace[1439253908] 'applied index is now lower than readState.Index' (duration: 17.257697ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:44:48.852787Z","caller":"traceutil/trace.go:171","msg":"trace[1521913599] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"154.983595ms","start":"2026-04-11T17:44:48.697748Z","end":"2026-04-11T17:44:48.852732Z","steps":["trace[1521913599] 'process raft request' (duration: 137.72166ms)","trace[1521913599] 'compare' (duration: 17.055492ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:44:48.853154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.157403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-04-11T17:44:48.85332Z","caller":"traceutil/trace.go:171","msg":"trace[1222445128] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1885; }","duration":"128.445851ms","start":"2026-04-11T17:44:48.724832Z","end":"2026-04-11T17:44:48.853277Z","steps":["trace[1222445128] 'agreement among raft nodes before linearized reading' (duration: 127.94256ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:45:25.581118Z","caller":"traceutil/trace.go:171","msg":"trace[398787805] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"156.830892ms","start":"2026-04-11T17:45:25.42425Z","end":"2026-04-11T17:45:25.581081Z","steps":["trace[398787805] 'process raft request' (duration: 156.586131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:45:25.86945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.071996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:45:25.869887Z","caller":"traceutil/trace.go:171","msg":"trace[1351813212] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"281.077217ms","start":"2026-04-11T17:45:25.588782Z","end":"2026-04-11T17:45:25.869859Z","steps":["trace[1351813212] 'process raft request' (duration: 95.474019ms)","trace[1351813212] 'compare' (duration: 184.79732ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:45:25.870147Z","caller":"traceutil/trace.go:171","msg":"trace[685741390] linearizableReadLoop","detail":"{readStateIndex:2222; appliedIndex:2221; }","duration":"185.530403ms","start":"2026-04-11T17:45:25.684285Z","end":"2026-04-11T17:45:25.869815Z","steps":["trace[685741390] 'read index received' (duration: 37.492µs)","trace[685741390] 'applied index is now lower than readState.Index' (duration: 185.490551ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:45:25.870542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.325459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-11T17:45:25.870797Z","caller":"traceutil/trace.go:171","msg":"trace[720189806] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2153; }","duration":"176.599465ms","start":"2026-04-11T17:45:25.694176Z","end":"2026-04-11T17:45:25.870775Z","steps":["trace[720189806] 'agreement among raft nodes before linearized reading' (duration: 176.199465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:45:25.870561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.5404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-04-11T17:45:25.870204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.910407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-11T17:45:25.871175Z","caller":"traceutil/trace.go:171","msg":"trace[175243615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2153; }","duration":"186.912054ms","start":"2026-04-11T17:45:25.684232Z","end":"2026-04-11T17:45:25.871144Z","steps":["trace[175243615] 'agreement among raft nodes before linearized reading' (duration: 185.846559ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:45:25.871582Z","caller":"traceutil/trace.go:171","msg":"trace[770831424] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2153; }","duration":"157.148818ms","start":"2026-04-11T17:45:25.713941Z","end":"2026-04-11T17:45:25.87109Z","steps":["trace[770831424] 'agreement among raft nodes before linearized reading' (duration: 156.478973ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:46:11.105003Z","caller":"traceutil/trace.go:171","msg":"trace[1656987238] transaction","detail":"{read_only:false; response_revision:2566; number_of_response:1; }","duration":"112.817178ms","start":"2026-04-11T17:46:10.992173Z","end":"2026-04-11T17:46:11.104991Z","steps":["trace[1656987238] 'process raft request' (duration: 112.508017ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:46:11.697052Z","caller":"traceutil/trace.go:171","msg":"trace[1992339302] linearizableReadLoop","detail":"{readStateIndex:2657; appliedIndex:2656; }","duration":"262.380815ms","start":"2026-04-11T17:46:11.434648Z","end":"2026-04-11T17:46:11.697028Z","steps":["trace[1992339302] 'read index received' (duration: 262.153733ms)","trace[1992339302] 'applied index is now lower than readState.Index' (duration: 225.611µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:46:11.697322Z","caller":"traceutil/trace.go:171","msg":"trace[1254567022] transaction","detail":"{read_only:false; response_revision:2567; number_of_response:1; }","duration":"262.728049ms","start":"2026-04-11T17:46:11.434582Z","end":"2026-04-11T17:46:11.69731Z","steps":["trace[1254567022] 'process raft request' (duration: 262.294017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:46:11.697623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.9574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-04-11T17:46:11.697668Z","caller":"traceutil/trace.go:171","msg":"trace[1825697463] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2567; }","duration":"263.054629ms","start":"2026-04-11T17:46:11.434603Z","end":"2026-04-11T17:46:11.697658Z","steps":["trace[1825697463] 'agreement among raft nodes before linearized reading' (duration: 262.901604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:46:11.697845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.472754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:46:11.697882Z","caller":"traceutil/trace.go:171","msg":"trace[1270531083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2567; }","duration":"204.522748ms","start":"2026-04-11T17:46:11.493351Z","end":"2026-04-11T17:46:11.697874Z","steps":["trace[1270531083] 'agreement among raft nodes before linearized reading' (duration: 204.465873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:46:11.698066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.900649ms","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-04-11T17:46:11.698108Z","caller":"traceutil/trace.go:171","msg":"trace[627580911] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2567; }","duration":"127.990837ms","start":"2026-04-11T17:46:11.570107Z","end":"2026-04-11T17:46:11.698098Z","steps":["trace[627580911] 'agreement among raft nodes before linearized reading' (duration: 127.928211ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:46:39.442472Z","caller":"traceutil/trace.go:171","msg":"trace[843296764] linearizableReadLoop","detail":"{readStateIndex:2863; appliedIndex:2862; }","duration":"158.763595ms","start":"2026-04-11T17:46:39.283686Z","end":"2026-04-11T17:46:39.44245Z","steps":["trace[843296764] 'read index received' (duration: 158.536312ms)","trace[843296764] 'applied index is now lower than readState.Index' (duration: 226.443µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:46:39.442726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.014556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-04-11T17:46:39.442763Z","caller":"traceutil/trace.go:171","msg":"trace[1849520470] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2762; }","duration":"159.115238ms","start":"2026-04-11T17:46:39.283634Z","end":"2026-04-11T17:46:39.442749Z","steps":["trace[1849520470] 'agreement among raft nodes before linearized reading' (duration: 158.940037ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:46:39.4431Z","caller":"traceutil/trace.go:171","msg":"trace[1996357249] transaction","detail":"{read_only:false; response_revision:2762; number_of_response:1; }","duration":"206.762923ms","start":"2026-04-11T17:46:39.236295Z","end":"2026-04-11T17:46:39.443058Z","steps":["trace[1996357249] 'process raft request' (duration: 206.022627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:46:40.097444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.556674ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-11T17:46:40.097565Z","caller":"traceutil/trace.go:171","msg":"trace[1912427213] linearizableReadLoop","detail":"{readStateIndex:2865; appliedIndex:2864; }","duration":"283.054565ms","start":"2026-04-11T17:46:39.814487Z","end":"2026-04-11T17:46:40.097542Z","steps":["trace[1912427213] 'read index received' (duration: 25.52µs)","trace[1912427213] 'applied index is now lower than readState.Index' (duration: 283.027385ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:46:40.097968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.946803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-04-11T17:46:40.098023Z","caller":"traceutil/trace.go:171","msg":"trace[1989834377] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2763; }","duration":"102.038384ms","start":"2026-04-11T17:46:39.995973Z","end":"2026-04-11T17:46:40.098011Z","steps":["trace[1989834377] 'agreement among raft nodes before linearized reading' (duration: 101.869853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:46:40.09861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.99863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-11T17:46:40.098666Z","caller":"traceutil/trace.go:171","msg":"trace[1236330026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2763; }","duration":"284.199362ms","start":"2026-04-11T17:46:39.814453Z","end":"2026-04-11T17:46:40.098652Z","steps":["trace[1236330026] 'agreement among raft nodes before linearized reading' (duration: 283.91145ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:44.978361Z","caller":"traceutil/trace.go:171","msg":"trace[815738544] linearizableReadLoop","detail":"{readStateIndex:3427; appliedIndex:3426; }","duration":"139.199893ms","start":"2026-04-11T17:47:44.839136Z","end":"2026-04-11T17:47:44.978336Z","steps":["trace[815738544] 'read index received' (duration: 44.434897ms)","trace[815738544] 'applied index is now lower than readState.Index' (duration: 94.763326ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:44.97843Z","caller":"traceutil/trace.go:171","msg":"trace[1645545135] transaction","detail":"{read_only:false; response_revision:3296; number_of_response:1; }","duration":"140.263032ms","start":"2026-04-11T17:47:44.838152Z","end":"2026-04-11T17:47:44.978415Z","steps":["trace[1645545135] 'process raft request' (duration: 45.411614ms)","trace[1645545135] 'compare' (duration: 94.656877ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:47:44.978644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.480356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-11T17:47:44.978672Z","caller":"traceutil/trace.go:171","msg":"trace[300851778] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3296; }","duration":"139.54581ms","start":"2026-04-11T17:47:44.839118Z","end":"2026-04-11T17:47:44.978664Z","steps":["trace[300851778] 'agreement among raft nodes before linearized reading' (duration: 139.397075ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:49.408143Z","caller":"traceutil/trace.go:171","msg":"trace[888886367] transaction","detail":"{read_only:false; response_revision:3325; number_of_response:1; }","duration":"108.781662ms","start":"2026-04-11T17:47:49.299333Z","end":"2026-04-11T17:47:49.408114Z","steps":["trace[888886367] 'process raft request' (duration: 108.49903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:49.677857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.72125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:47:49.678035Z","caller":"traceutil/trace.go:171","msg":"trace[268991264] linearizableReadLoop","detail":"{readStateIndex:3460; appliedIndex:3459; }","duration":"227.260999ms","start":"2026-04-11T17:47:49.450734Z","end":"2026-04-11T17:47:49.677995Z","steps":["trace[268991264] 'read index received' (duration: 109.275834ms)","trace[268991264] 'applied index is now lower than readState.Index' (duration: 117.982575ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:47:49.678249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.787818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:47:49.678348Z","caller":"traceutil/trace.go:171","msg":"trace[950991962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3328; }","duration":"181.909416ms","start":"2026-04-11T17:47:49.496419Z","end":"2026-04-11T17:47:49.678328Z","steps":["trace[950991962] 'agreement among raft nodes before linearized reading' (duration: 181.759371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:47:49.678352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.596206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4830"} {"level":"info","ts":"2026-04-11T17:47:49.678407Z","caller":"traceutil/trace.go:171","msg":"trace[1502741167] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3328; }","duration":"227.677917ms","start":"2026-04-11T17:47:49.450712Z","end":"2026-04-11T17:47:49.67839Z","steps":["trace[1502741167] 'agreement among raft nodes before linearized reading' (duration: 227.440231ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:49.679436Z","caller":"traceutil/trace.go:171","msg":"trace[1924158643] transaction","detail":"{read_only:false; response_revision:3327; number_of_response:1; }","duration":"233.417792ms","start":"2026-04-11T17:47:49.445987Z","end":"2026-04-11T17:47:49.679405Z","steps":["trace[1924158643] 'process raft request' (duration: 114.038234ms)","trace[1924158643] 'compare' (duration: 117.62952ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:47:53.054342Z","caller":"traceutil/trace.go:171","msg":"trace[1243814597] transaction","detail":"{read_only:false; response_revision:3350; number_of_response:1; }","duration":"124.710649ms","start":"2026-04-11T17:47:52.929609Z","end":"2026-04-11T17:47:53.05432Z","steps":["trace[1243814597] 'process raft request' (duration: 124.538427ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:54.959279Z","caller":"traceutil/trace.go:171","msg":"trace[908327294] transaction","detail":"{read_only:false; response_revision:3363; number_of_response:1; }","duration":"124.159529ms","start":"2026-04-11T17:47:54.835083Z","end":"2026-04-11T17:47:54.959242Z","steps":["trace[908327294] 'process raft request' (duration: 123.769209ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:47:55.094054Z","caller":"traceutil/trace.go:171","msg":"trace[1237078454] transaction","detail":"{read_only:false; response_revision:3365; number_of_response:1; }","duration":"123.620308ms","start":"2026-04-11T17:47:54.97039Z","end":"2026-04-11T17:47:55.09401Z","steps":["trace[1237078454] 'process raft request' (duration: 76.521941ms)","trace[1237078454] 'compare' (duration: 46.904437ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:47:55.49238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.448314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:47:55.492606Z","caller":"traceutil/trace.go:171","msg":"trace[1746830645] transaction","detail":"{read_only:false; response_revision:3369; number_of_response:1; }","duration":"231.354202ms","start":"2026-04-11T17:47:55.261183Z","end":"2026-04-11T17:47:55.492537Z","steps":["trace[1746830645] 'process raft request' (duration: 100.649675ms)","trace[1746830645] 'compare' (duration: 130.28848ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:48:52.817579Z","caller":"traceutil/trace.go:171","msg":"trace[326402247] transaction","detail":"{read_only:false; response_revision:4262; number_of_response:1; }","duration":"107.806598ms","start":"2026-04-11T17:48:52.709733Z","end":"2026-04-11T17:48:52.817539Z","steps":["trace[326402247] 'process raft request' (duration: 27.878902ms)","trace[326402247] 'compare' (duration: 79.779346ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:48:56.120696Z","caller":"traceutil/trace.go:171","msg":"trace[278642280] transaction","detail":"{read_only:false; response_revision:4341; number_of_response:1; }","duration":"100.227708ms","start":"2026-04-11T17:48:56.020434Z","end":"2026-04-11T17:48:56.120661Z","steps":["trace[278642280] 'process raft request' (duration: 68.077068ms)","trace[278642280] 'compare' (duration: 31.716708ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:48:56.135154Z","caller":"traceutil/trace.go:171","msg":"trace[1824013970] transaction","detail":"{read_only:false; response_revision:4342; number_of_response:1; }","duration":"111.946558ms","start":"2026-04-11T17:48:56.023181Z","end":"2026-04-11T17:48:56.135128Z","steps":["trace[1824013970] 'process raft request' (duration: 111.798718ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:56.135532Z","caller":"traceutil/trace.go:171","msg":"trace[1654314848] transaction","detail":"{read_only:false; response_revision:4343; number_of_response:1; }","duration":"107.872611ms","start":"2026-04-11T17:48:56.027646Z","end":"2026-04-11T17:48:56.135519Z","steps":["trace[1654314848] 'process raft request' (duration: 107.431559ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:56.303809Z","caller":"traceutil/trace.go:171","msg":"trace[879934616] transaction","detail":"{read_only:false; response_revision:4348; number_of_response:1; }","duration":"115.222141ms","start":"2026-04-11T17:48:56.188557Z","end":"2026-04-11T17:48:56.303779Z","steps":["trace[879934616] 'process raft request' (duration: 115.129884ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:56.303832Z","caller":"traceutil/trace.go:171","msg":"trace[1065559316] linearizableReadLoop","detail":"{readStateIndex:4534; appliedIndex:4529; }","duration":"112.746421ms","start":"2026-04-11T17:48:56.19107Z","end":"2026-04-11T17:48:56.303816Z","steps":["trace[1065559316] 'read index received' (duration: 3.579673ms)","trace[1065559316] 'applied index is now lower than readState.Index' (duration: 109.165658ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:48:56.303859Z","caller":"traceutil/trace.go:171","msg":"trace[1194799023] transaction","detail":"{read_only:false; response_revision:4351; number_of_response:1; }","duration":"115.08407ms","start":"2026-04-11T17:48:56.188743Z","end":"2026-04-11T17:48:56.303827Z","steps":["trace[1194799023] 'process raft request' (duration: 115.00992ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:56.303948Z","caller":"traceutil/trace.go:171","msg":"trace[15408337] transaction","detail":"{read_only:false; response_revision:4349; number_of_response:1; }","duration":"115.306391ms","start":"2026-04-11T17:48:56.188628Z","end":"2026-04-11T17:48:56.303934Z","steps":["trace[15408337] 'process raft request' (duration: 115.0841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:56.303969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.875276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-11T17:48:56.304382Z","caller":"traceutil/trace.go:171","msg":"trace[60600429] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4352; }","duration":"113.312487ms","start":"2026-04-11T17:48:56.191044Z","end":"2026-04-11T17:48:56.304356Z","steps":["trace[60600429] 'agreement among raft nodes before linearized reading' (duration: 112.828881ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:56.303989Z","caller":"traceutil/trace.go:171","msg":"trace[1422807097] transaction","detail":"{read_only:false; response_revision:4350; number_of_response:1; }","duration":"115.326969ms","start":"2026-04-11T17:48:56.188642Z","end":"2026-04-11T17:48:56.303969Z","steps":["trace[1422807097] 'process raft request' (duration: 115.08918ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:56.303998Z","caller":"traceutil/trace.go:171","msg":"trace[804358256] transaction","detail":"{read_only:false; response_revision:4352; number_of_response:1; }","duration":"109.653014ms","start":"2026-04-11T17:48:56.194337Z","end":"2026-04-11T17:48:56.30399Z","steps":["trace[804358256] 'process raft request' (duration: 109.437062ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:56.304031Z","caller":"traceutil/trace.go:171","msg":"trace[1344314515] transaction","detail":"{read_only:false; response_revision:4347; number_of_response:1; }","duration":"120.858103ms","start":"2026-04-11T17:48:56.183165Z","end":"2026-04-11T17:48:56.304023Z","steps":["trace[1344314515] 'process raft request' (duration: 113.167926ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:57.558366Z","caller":"traceutil/trace.go:171","msg":"trace[1812844048] linearizableReadLoop","detail":"{readStateIndex:4561; appliedIndex:4560; }","duration":"313.186011ms","start":"2026-04-11T17:48:57.245159Z","end":"2026-04-11T17:48:57.558345Z","steps":["trace[1812844048] 'read index received' (duration: 265.621706ms)","trace[1812844048] 'applied index is now lower than readState.Index' (duration: 47.563646ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:48:57.558395Z","caller":"traceutil/trace.go:171","msg":"trace[996233450] transaction","detail":"{read_only:false; response_revision:4375; number_of_response:1; }","duration":"422.301586ms","start":"2026-04-11T17:48:57.136065Z","end":"2026-04-11T17:48:57.558367Z","steps":["trace[996233450] 'process raft request' (duration: 374.729251ms)","trace[996233450] 'compare' (duration: 47.179647ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:48:57.558613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.771543ms","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-04-11T17:48:57.558676Z","caller":"traceutil/trace.go:171","msg":"trace[817007755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4375; }","duration":"169.883668ms","start":"2026-04-11T17:48:57.38878Z","end":"2026-04-11T17:48:57.558664Z","steps":["trace[817007755] 'agreement among raft nodes before linearized reading' (duration: 169.717241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:57.558801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:57.136043Z","time spent":"422.596276ms","remote":"127.0.0.1:51926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:48:57.558551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.380025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-85xpr\" ","response":"range_response_count:1 size:5141"} {"level":"info","ts":"2026-04-11T17:48:57.559009Z","caller":"traceutil/trace.go:171","msg":"trace[7071004] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-85xpr; range_end:; response_count:1; response_revision:4375; }","duration":"313.888466ms","start":"2026-04-11T17:48:57.245102Z","end":"2026-04-11T17:48:57.558991Z","steps":["trace[7071004] 'agreement among raft nodes before linearized reading' (duration: 313.34337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:57.559087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:57.245082Z","time spent":"313.991744ms","remote":"127.0.0.1:51838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":5163,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-85xpr\" "} {"level":"warn","ts":"2026-04-11T17:48:57.558607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.250916ms","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-04-11T17:48:57.559348Z","caller":"traceutil/trace.go:171","msg":"trace[1680762950] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4375; }","duration":"165.882891ms","start":"2026-04-11T17:48:57.393311Z","end":"2026-04-11T17:48:57.559194Z","steps":["trace[1680762950] 'agreement among raft nodes before linearized reading' (duration: 165.260584ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:02.533133Z","caller":"traceutil/trace.go:171","msg":"trace[18765418] linearizableReadLoop","detail":"{readStateIndex:4666; appliedIndex:4665; }","duration":"168.244137ms","start":"2026-04-11T17:49:02.364866Z","end":"2026-04-11T17:49:02.53311Z","steps":["trace[18765418] 'read index received' (duration: 168.097956ms)","trace[18765418] 'applied index is now lower than readState.Index' (duration: 145.261µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:02.533268Z","caller":"traceutil/trace.go:171","msg":"trace[909674804] transaction","detail":"{read_only:false; response_revision:4478; number_of_response:1; }","duration":"171.976144ms","start":"2026-04-11T17:49:02.361259Z","end":"2026-04-11T17:49:02.533235Z","steps":["trace[909674804] 'process raft request' (duration: 171.711439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:02.533548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.66028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:02.533628Z","caller":"traceutil/trace.go:171","msg":"trace[956824616] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:4478; }","duration":"168.788343ms","start":"2026-04-11T17:49:02.364824Z","end":"2026-04-11T17:49:02.533612Z","steps":["trace[956824616] 'agreement among raft nodes before linearized reading' (duration: 168.674688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:02.798936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.764735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-11T17:49:02.799026Z","caller":"traceutil/trace.go:171","msg":"trace[28861232] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4478; }","duration":"187.909285ms","start":"2026-04-11T17:49:02.611093Z","end":"2026-04-11T17:49:02.799003Z","steps":["trace[28861232] 'range keys from in-memory index tree' (duration: 187.529167ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:15.467853Z","caller":"traceutil/trace.go:171","msg":"trace[1599880225] linearizableReadLoop","detail":"{readStateIndex:4856; appliedIndex:4855; }","duration":"496.024791ms","start":"2026-04-11T17:49:14.971806Z","end":"2026-04-11T17:49:15.467831Z","steps":["trace[1599880225] 'read index received' (duration: 495.81547ms)","trace[1599880225] 'applied index is now lower than readState.Index' (duration: 208.531µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:15.46813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.314691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-11T17:49:15.46818Z","caller":"traceutil/trace.go:171","msg":"trace[134640902] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4659; }","duration":"496.419056ms","start":"2026-04-11T17:49:14.971746Z","end":"2026-04-11T17:49:15.468165Z","steps":["trace[134640902] 'agreement among raft nodes before linearized reading' (duration: 496.198006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:15.468211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:14.971727Z","time spent":"496.475929ms","remote":"127.0.0.1:51796","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-04-11T17:49:15.468334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.373302ms","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-04-11T17:49:15.468321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.27078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T17:49:15.468393Z","caller":"traceutil/trace.go:171","msg":"trace[1580643461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4659; }","duration":"287.45859ms","start":"2026-04-11T17:49:15.180919Z","end":"2026-04-11T17:49:15.468377Z","steps":["trace[1580643461] 'agreement among raft nodes before linearized reading' (duration: 287.225242ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:15.468412Z","caller":"traceutil/trace.go:171","msg":"trace[1502203932] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:4659; }","duration":"480.401451ms","start":"2026-04-11T17:49:14.987988Z","end":"2026-04-11T17:49:15.468389Z","steps":["trace[1502203932] 'agreement among raft nodes before linearized reading' (duration: 480.167344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:15.46847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:14.987943Z","time spent":"480.515846ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":72,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:49:15.468984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.08976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-11T17:49:15.46904Z","caller":"traceutil/trace.go:171","msg":"trace[579475743] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4659; }","duration":"495.198845ms","start":"2026-04-11T17:49:14.973826Z","end":"2026-04-11T17:49:15.469025Z","steps":["trace[579475743] 'agreement among raft nodes before linearized reading' (duration: 494.283291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:15.469075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:14.973814Z","time spent":"495.253868ms","remote":"127.0.0.1:51740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-11T17:49:15.467973Z","caller":"traceutil/trace.go:171","msg":"trace[2142212212] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"519.366669ms","start":"2026-04-11T17:49:14.948576Z","end":"2026-04-11T17:49:15.467943Z","steps":["trace[2142212212] 'process raft request' (duration: 519.116263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:15.469642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:14.948553Z","time spent":"521.003494ms","remote":"127.0.0.1:51838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9713,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:49:15.574929Z","caller":"traceutil/trace.go:171","msg":"trace[1461736974] transaction","detail":"{read_only:false; response_revision:4660; number_of_response:1; }","duration":"100.236621ms","start":"2026-04-11T17:49:15.474657Z","end":"2026-04-11T17:49:15.574893Z","steps":["trace[1461736974] 'process raft request' (duration: 86.614939ms)","trace[1461736974] 'compare' (duration: 13.498989ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:15.638588Z","caller":"traceutil/trace.go:171","msg":"trace[1199843777] transaction","detail":"{read_only:false; response_revision:4662; number_of_response:1; }","duration":"157.886642ms","start":"2026-04-11T17:49:15.480679Z","end":"2026-04-11T17:49:15.638565Z","steps":["trace[1199843777] 'process raft request' (duration: 157.812783ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:15.63865Z","caller":"traceutil/trace.go:171","msg":"trace[181821989] transaction","detail":"{read_only:false; response_revision:4661; number_of_response:1; }","duration":"161.862544ms","start":"2026-04-11T17:49:15.476758Z","end":"2026-04-11T17:49:15.63862Z","steps":["trace[181821989] 'process raft request' (duration: 161.534659ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:15.764637Z","caller":"traceutil/trace.go:171","msg":"trace[1114804342] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"117.629342ms","start":"2026-04-11T17:49:15.646985Z","end":"2026-04-11T17:49:15.764615Z","steps":["trace[1114804342] 'process raft request' (duration: 117.382297ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:15.764758Z","caller":"traceutil/trace.go:171","msg":"trace[1849131504] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"117.177875ms","start":"2026-04-11T17:49:15.647563Z","end":"2026-04-11T17:49:15.76474Z","steps":["trace[1849131504] 'process raft request' (duration: 116.92239ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:15.76477Z","caller":"traceutil/trace.go:171","msg":"trace[1700916154] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"116.664405ms","start":"2026-04-11T17:49:15.648095Z","end":"2026-04-11T17:49:15.764759Z","steps":["trace[1700916154] 'process raft request' (duration: 116.499668ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:15.7647Z","caller":"traceutil/trace.go:171","msg":"trace[48550617] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"116.77317ms","start":"2026-04-11T17:49:15.647906Z","end":"2026-04-11T17:49:15.764679Z","steps":["trace[48550617] 'process raft request' (duration: 116.650477ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:15.93648Z","caller":"traceutil/trace.go:171","msg":"trace[1085149684] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"140.389415ms","start":"2026-04-11T17:49:15.796057Z","end":"2026-04-11T17:49:15.936446Z","steps":["trace[1085149684] 'process raft request' (duration: 73.18816ms)","trace[1085149684] 'compare' (duration: 66.564723ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:15.936595Z","caller":"traceutil/trace.go:171","msg":"trace[1740980594] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"121.584218ms","start":"2026-04-11T17:49:15.81499Z","end":"2026-04-11T17:49:15.936574Z","steps":["trace[1740980594] 'process raft request' (duration: 121.246444ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:16.600889Z","caller":"traceutil/trace.go:171","msg":"trace[113829617] linearizableReadLoop","detail":"{readStateIndex:4872; appliedIndex:4871; }","duration":"170.56327ms","start":"2026-04-11T17:49:16.43031Z","end":"2026-04-11T17:49:16.600873Z","steps":["trace[113829617] 'read index received' (duration: 137.121107ms)","trace[113829617] 'applied index is now lower than readState.Index' (duration: 33.441213ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:16.601177Z","caller":"traceutil/trace.go:171","msg":"trace[871388374] transaction","detail":"{read_only:false; response_revision:4675; number_of_response:1; }","duration":"181.751924ms","start":"2026-04-11T17:49:16.419228Z","end":"2026-04-11T17:49:16.60098Z","steps":["trace[871388374] 'process raft request' (duration: 148.17782ms)","trace[871388374] 'compare' (duration: 33.378451ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:16.601352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.020937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3198"} {"level":"info","ts":"2026-04-11T17:49:16.601407Z","caller":"traceutil/trace.go:171","msg":"trace[1171506952] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4675; }","duration":"171.135ms","start":"2026-04-11T17:49:16.430257Z","end":"2026-04-11T17:49:16.601392Z","steps":["trace[1171506952] 'agreement among raft nodes before linearized reading' (duration: 170.933677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:16.601517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.987636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:16.601552Z","caller":"traceutil/trace.go:171","msg":"trace[267896559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4675; }","duration":"106.235431ms","start":"2026-04-11T17:49:16.495307Z","end":"2026-04-11T17:49:16.601543Z","steps":["trace[267896559] 'agreement among raft nodes before linearized reading' (duration: 105.999714ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:39.412877Z","caller":"traceutil/trace.go:171","msg":"trace[1532982275] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"184.424183ms","start":"2026-04-11T17:49:39.228416Z","end":"2026-04-11T17:49:39.41284Z","steps":["trace[1532982275] 'process raft request' (duration: 184.208994ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:39.412932Z","caller":"traceutil/trace.go:171","msg":"trace[393983426] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"187.314773ms","start":"2026-04-11T17:49:39.225598Z","end":"2026-04-11T17:49:39.412913Z","steps":["trace[393983426] 'process raft request' (duration: 161.290671ms)","trace[393983426] 'compare' (duration: 25.622878ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:39.41288Z","caller":"traceutil/trace.go:171","msg":"trace[606986993] linearizableReadLoop","detail":"{readStateIndex:5137; appliedIndex:5135; }","duration":"151.736549ms","start":"2026-04-11T17:49:39.261114Z","end":"2026-04-11T17:49:39.412851Z","steps":["trace[606986993] 'read index received' (duration: 125.861078ms)","trace[606986993] 'applied index is now lower than readState.Index' (duration: 25.872821ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:39.420005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.293612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-11T17:49:39.42006Z","caller":"traceutil/trace.go:171","msg":"trace[1284724258] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4930; }","duration":"193.391599ms","start":"2026-04-11T17:49:39.226656Z","end":"2026-04-11T17:49:39.420048Z","steps":["trace[1284724258] 'agreement among raft nodes before linearized reading' (duration: 186.290029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:39.420404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.897472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-04-11T17:49:39.420484Z","caller":"traceutil/trace.go:171","msg":"trace[314878568] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4931; }","duration":"131.012985ms","start":"2026-04-11T17:49:39.289453Z","end":"2026-04-11T17:49:39.420466Z","steps":["trace[314878568] 'agreement among raft nodes before linearized reading' (duration: 130.387324ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:39.55912Z","caller":"traceutil/trace.go:171","msg":"trace[1621519662] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"100.52087ms","start":"2026-04-11T17:49:39.458579Z","end":"2026-04-11T17:49:39.5591Z","steps":["trace[1621519662] 'process raft request' (duration: 100.10303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:39.559366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.74153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"warn","ts":"2026-04-11T17:49:39.55942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.877069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/keystone-api\" ","response":"range_response_count:1 size:11119"} {"level":"info","ts":"2026-04-11T17:49:39.559483Z","caller":"traceutil/trace.go:171","msg":"trace[257257043] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4934; }","duration":"109.908736ms","start":"2026-04-11T17:49:39.449525Z","end":"2026-04-11T17:49:39.559434Z","steps":["trace[257257043] 'agreement among raft nodes before linearized reading' (duration: 109.611288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:39.559436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.726523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-11T17:49:39.55951Z","caller":"traceutil/trace.go:171","msg":"trace[154123603] range","detail":"{range_begin:/registry/deployments/openstack/keystone-api; range_end:; response_count:1; response_revision:4934; }","duration":"119.987553ms","start":"2026-04-11T17:49:39.439509Z","end":"2026-04-11T17:49:39.559497Z","steps":["trace[154123603] 'agreement among raft nodes before linearized reading' (duration: 119.806449ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:39.559679Z","caller":"traceutil/trace.go:171","msg":"trace[1646782871] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4934; }","duration":"119.023151ms","start":"2026-04-11T17:49:39.440629Z","end":"2026-04-11T17:49:39.559652Z","steps":["trace[1646782871] 'agreement among raft nodes before linearized reading' (duration: 118.487656ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:39.584364Z","caller":"traceutil/trace.go:171","msg":"trace[181715618] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"108.643156ms","start":"2026-04-11T17:49:39.475702Z","end":"2026-04-11T17:49:39.584345Z","steps":["trace[181715618] 'process raft request' (duration: 108.433496ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:39.584365Z","caller":"traceutil/trace.go:171","msg":"trace[1797011479] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"107.781178ms","start":"2026-04-11T17:49:39.476566Z","end":"2026-04-11T17:49:39.584347Z","steps":["trace[1797011479] 'process raft request' (duration: 107.69636ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:39.58448Z","caller":"traceutil/trace.go:171","msg":"trace[1748225975] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"106.623734ms","start":"2026-04-11T17:49:39.477849Z","end":"2026-04-11T17:49:39.584473Z","steps":["trace[1748225975] 'process raft request' (duration: 106.445518ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:39.584784Z","caller":"traceutil/trace.go:171","msg":"trace[117810249] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"103.12536ms","start":"2026-04-11T17:49:39.481643Z","end":"2026-04-11T17:49:39.584768Z","steps":["trace[117810249] 'process raft request' (duration: 102.78063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:39.584884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.423463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4726"} {"level":"info","ts":"2026-04-11T17:49:39.58492Z","caller":"traceutil/trace.go:171","msg":"trace[1623689830] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4938; }","duration":"105.489784ms","start":"2026-04-11T17:49:39.47942Z","end":"2026-04-11T17:49:39.58491Z","steps":["trace[1623689830] 'agreement among raft nodes before linearized reading' (duration: 105.345835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:11.227682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.309029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:50:11.227849Z","caller":"traceutil/trace.go:171","msg":"trace[2050240136] transaction","detail":"{read_only:false; response_revision:5227; number_of_response:1; }","duration":"123.139572ms","start":"2026-04-11T17:50:11.104694Z","end":"2026-04-11T17:50:11.227834Z","steps":["trace[2050240136] 'compare' (duration: 119.008893ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:16.624563Z","caller":"traceutil/trace.go:171","msg":"trace[1842612206] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"104.534397ms","start":"2026-04-11T17:50:16.519987Z","end":"2026-04-11T17:50:16.624521Z","steps":["trace[1842612206] 'process raft request' (duration: 102.764862ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:16.62462Z","caller":"traceutil/trace.go:171","msg":"trace[49150316] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"101.65593ms","start":"2026-04-11T17:50:16.522914Z","end":"2026-04-11T17:50:16.62457Z","steps":["trace[49150316] 'process raft request' (duration: 101.47631ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:41.227843Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3273} {"level":"info","ts":"2026-04-11T17:52:41.413906Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3273,"took":"184.787124ms","hash":3013957372,"current-db-size-bytes":59973632,"current-db-size":"60 MB","current-db-size-in-use-bytes":32428032,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-11T17:52:41.413961Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3013957372,"revision":3273,"compact-revision":-1} {"level":"info","ts":"2026-04-11T17:52:43.458322Z","caller":"traceutil/trace.go:171","msg":"trace[1556023734] transaction","detail":"{read_only:false; response_revision:6493; number_of_response:1; }","duration":"231.22492ms","start":"2026-04-11T17:52:43.22701Z","end":"2026-04-11T17:52:43.458235Z","steps":["trace[1556023734] 'process raft request' (duration: 231.000493ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:58.060939Z","caller":"traceutil/trace.go:171","msg":"trace[972830498] transaction","detail":"{read_only:false; response_revision:6604; number_of_response:1; }","duration":"114.690536ms","start":"2026-04-11T17:52:57.946225Z","end":"2026-04-11T17:52:58.060916Z","steps":["trace[972830498] 'process raft request' (duration: 114.53274ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:58.305702Z","caller":"traceutil/trace.go:171","msg":"trace[211332140] transaction","detail":"{read_only:false; response_revision:6607; number_of_response:1; }","duration":"186.87369ms","start":"2026-04-11T17:52:58.1188Z","end":"2026-04-11T17:52:58.305674Z","steps":["trace[211332140] 'process raft request' (duration: 141.493813ms)","trace[211332140] 'compare' (duration: 45.259931ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:52:58.350146Z","caller":"traceutil/trace.go:171","msg":"trace[776278162] linearizableReadLoop","detail":"{readStateIndex:6902; appliedIndex:6899; }","duration":"221.428924ms","start":"2026-04-11T17:52:58.128696Z","end":"2026-04-11T17:52:58.350125Z","steps":["trace[776278162] 'read index received' (duration: 131.66319ms)","trace[776278162] 'applied index is now lower than readState.Index' (duration: 89.764844ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:52:58.350171Z","caller":"traceutil/trace.go:171","msg":"trace[1090458777] transaction","detail":"{read_only:false; response_revision:6608; number_of_response:1; }","duration":"231.059832ms","start":"2026-04-11T17:52:58.119094Z","end":"2026-04-11T17:52:58.350154Z","steps":["trace[1090458777] 'process raft request' (duration: 230.898176ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:58.350206Z","caller":"traceutil/trace.go:171","msg":"trace[873449543] transaction","detail":"{read_only:false; number_of_response:1; response_revision:6608; }","duration":"225.721401ms","start":"2026-04-11T17:52:58.12448Z","end":"2026-04-11T17:52:58.350201Z","steps":["trace[873449543] 'process raft request' (duration: 225.614794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:52:58.350437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.722874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/ingress-nginx/ingress-nginx-controller-pfjnd.18a55e6ff50db61a\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-04-11T17:52:58.350466Z","caller":"traceutil/trace.go:171","msg":"trace[622155924] range","detail":"{range_begin:/registry/events/ingress-nginx/ingress-nginx-controller-pfjnd.18a55e6ff50db61a; range_end:; response_count:1; response_revision:6608; }","duration":"221.790644ms","start":"2026-04-11T17:52:58.128667Z","end":"2026-04-11T17:52:58.350458Z","steps":["trace[622155924] 'agreement among raft nodes before linearized reading' (duration: 221.686697ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:53:14.889579Z","caller":"traceutil/trace.go:171","msg":"trace[1851032537] transaction","detail":"{read_only:false; response_revision:6752; number_of_response:1; }","duration":"119.401053ms","start":"2026-04-11T17:53:14.770155Z","end":"2026-04-11T17:53:14.889556Z","steps":["trace[1851032537] 'process raft request' (duration: 99.790778ms)","trace[1851032537] 'compare' (duration: 19.047366ms)"],"step_count":2}