{"level":"warn","ts":"2026-05-19T22:14:46.512217Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T22:14:46.512342Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-05-19T22:14:46.512442Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T22:14:46.512462Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-05-19T22:14:46.512494Z","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-05-19T22:14:46.513311Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-05-19T22:14:46.513387Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-05-19T22:14:46.518137Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.508241ms"} {"level":"info","ts":"2026-05-19T22:14:46.529239Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-05-19T22:14:46.529305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T22:14:46.529325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-05-19T22:14:46.529338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T22:14:46.529348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-05-19T22:14:46.529394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-05-19T22:14:46.535464Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T22:14:46.537571Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T22:14:46.539002Z","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-05-19T22:14:46.540952Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T22:14:46.541329Z","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-05-19T22:14:46.541391Z","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-05-19T22:14:46.541415Z","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-05-19T22:14:46.541329Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T22:14:46.541899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-05-19T22:14:46.541975Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-05-19T22:14:46.546718Z","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-05-19T22:14:46.548557Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T22:14:46.548645Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T22:14:46.546925Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-05-19T22:14:46.548782Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-05-19T22:14:46.83033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T22:14:46.830458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T22:14:46.830506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-05-19T22:14:46.830563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-05-19T22:14:46.830599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-05-19T22:14:46.830646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-05-19T22:14:46.830708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-05-19T22:14:46.831461Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:14:46.832428Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T22:14:46.832642Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T22:14:46.832725Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T22:14:46.832939Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:14:46.840719Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:14:46.832836Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T22:14:46.836752Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-05-19T22:14:46.840898Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:14:46.840986Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T22:14:46.856609Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T22:15:15.444156Z","caller":"traceutil/trace.go:171","msg":"trace[592591783] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"140.208092ms","start":"2026-05-19T22:15:15.303925Z","end":"2026-05-19T22:15:15.444134Z","steps":["trace[592591783] 'process raft request' (duration: 140.078151ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:15:15.450269Z","caller":"traceutil/trace.go:171","msg":"trace[1582533286] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"145.539262ms","start":"2026-05-19T22:15:15.30471Z","end":"2026-05-19T22:15:15.450249Z","steps":["trace[1582533286] 'process raft request' (duration: 145.410281ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:15:15.450549Z","caller":"traceutil/trace.go:171","msg":"trace[297894657] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"137.255434ms","start":"2026-05-19T22:15:15.313277Z","end":"2026-05-19T22:15:15.450533Z","steps":["trace[297894657] 'process raft request' (duration: 136.961013ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:15:15.450497Z","caller":"traceutil/trace.go:171","msg":"trace[73473311] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"141.948152ms","start":"2026-05-19T22:15:15.308495Z","end":"2026-05-19T22:15:15.450443Z","steps":["trace[73473311] 'process raft request' (duration: 141.70362ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:15:15.450622Z","caller":"traceutil/trace.go:171","msg":"trace[1591437663] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"137.257135ms","start":"2026-05-19T22:15:15.313319Z","end":"2026-05-19T22:15:15.450576Z","steps":["trace[1591437663] 'process raft request' (duration: 136.975193ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:15:15.45079Z","caller":"traceutil/trace.go:171","msg":"trace[374377685] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"137.311645ms","start":"2026-05-19T22:15:15.313466Z","end":"2026-05-19T22:15:15.450778Z","steps":["trace[374377685] 'process raft request' (duration: 137.041123ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:15:15.450889Z","caller":"traceutil/trace.go:171","msg":"trace[1451913541] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"131.912134ms","start":"2026-05-19T22:15:15.318966Z","end":"2026-05-19T22:15:15.450878Z","steps":["trace[1451913541] 'process raft request' (duration: 131.693723ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:15:15.450864Z","caller":"traceutil/trace.go:171","msg":"trace[572673584] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"135.302683ms","start":"2026-05-19T22:15:15.315493Z","end":"2026-05-19T22:15:15.450796Z","steps":["trace[572673584] 'process raft request' (duration: 135.059561ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:15:15.451009Z","caller":"traceutil/trace.go:171","msg":"trace[1260585181] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"127.134967ms","start":"2026-05-19T22:15:15.323854Z","end":"2026-05-19T22:15:15.450989Z","steps":["trace[1260585181] 'process raft request' (duration: 126.923316ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:15:15.451091Z","caller":"traceutil/trace.go:171","msg":"trace[1445948184] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"121.806806ms","start":"2026-05-19T22:15:15.329254Z","end":"2026-05-19T22:15:15.451061Z","steps":["trace[1445948184] 'process raft request' (duration: 121.578945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:15:19.927247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.250434ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:15:19.927358Z","caller":"traceutil/trace.go:171","msg":"trace[477978367] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"511.729472ms","start":"2026-05-19T22:15:19.415615Z","end":"2026-05-19T22:15:19.927344Z","steps":["trace[477978367] 'read index received' (duration: 30.923164ms)","trace[477978367] 'applied index is now lower than readState.Index' (duration: 480.804918ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:15:19.927459Z","caller":"traceutil/trace.go:171","msg":"trace[620254881] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"542.796433ms","start":"2026-05-19T22:15:19.384625Z","end":"2026-05-19T22:15:19.927422Z","steps":["trace[620254881] 'process raft request' (duration: 61.909671ms)","trace[620254881] 'compare' (duration: 479.560222ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:15:19.927591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.938872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:15:19.927659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:15:19.384593Z","time spent":"542.971491ms","remote":"127.0.0.1:47740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T22:15:19.927717Z","caller":"traceutil/trace.go:171","msg":"trace[1390277431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"512.085719ms","start":"2026-05-19T22:15:19.415601Z","end":"2026-05-19T22:15:19.927687Z","steps":["trace[1390277431] 'agreement among raft nodes before linearized reading' (duration: 511.840968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:15:19.92777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:15:19.415529Z","time spent":"512.234605ms","remote":"127.0.0.1:47412","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-05-19T22:15:22.049774Z","caller":"traceutil/trace.go:171","msg":"trace[309592484] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"200.462994ms","start":"2026-05-19T22:15:21.849291Z","end":"2026-05-19T22:15:22.049754Z","steps":["trace[309592484] 'read index received' (duration: 199.707951ms)","trace[309592484] 'applied index is now lower than readState.Index' (duration: 754.223µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:15:22.050125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.804088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-84kfm\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-19T22:15:22.050191Z","caller":"traceutil/trace.go:171","msg":"trace[1801028694] range","detail":"{range_begin:/registry/pods/kube-system/cilium-84kfm; range_end:; response_count:1; response_revision:616; }","duration":"200.924794ms","start":"2026-05-19T22:15:21.849253Z","end":"2026-05-19T22:15:22.050178Z","steps":["trace[1801028694] 'agreement among raft nodes before linearized reading' (duration: 200.58681ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:15:22.050926Z","caller":"traceutil/trace.go:171","msg":"trace[1631589227] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"275.967484ms","start":"2026-05-19T22:15:21.77494Z","end":"2026-05-19T22:15:22.050908Z","steps":["trace[1631589227] 'process raft request' (duration: 274.329973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:15:22.916458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831925435663894,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T22:15:23.194784Z","caller":"traceutil/trace.go:171","msg":"trace[1610627924] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"806.105423ms","start":"2026-05-19T22:15:22.388648Z","end":"2026-05-19T22:15:23.194753Z","steps":["trace[1610627924] 'process raft request' (duration: 805.923236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:15:23.195181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:15:22.388622Z","time spent":"806.25099ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:15:23.257679Z","caller":"traceutil/trace.go:171","msg":"trace[58267622] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"683.66233ms","start":"2026-05-19T22:15:22.573988Z","end":"2026-05-19T22:15:23.25765Z","steps":["trace[58267622] 'process raft request' (duration: 676.919141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:15:23.257897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:15:22.573956Z","time spent":"683.854409ms","remote":"127.0.0.1:47510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:15:23.258162Z","caller":"traceutil/trace.go:171","msg":"trace[957091695] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"540.004058ms","start":"2026-05-19T22:15:22.718144Z","end":"2026-05-19T22:15:23.258148Z","steps":["trace[957091695] 'process raft request' (duration: 539.451365ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:15:23.258151Z","caller":"traceutil/trace.go:171","msg":"trace[878635761] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:646; }","duration":"842.68946ms","start":"2026-05-19T22:15:22.415417Z","end":"2026-05-19T22:15:23.258107Z","steps":["trace[878635761] 'read index received' (duration: 779.202415ms)","trace[878635761] 'applied index is now lower than readState.Index' (duration: 63.484444ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:15:23.25827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:15:22.718111Z","time spent":"540.110894ms","remote":"127.0.0.1:47594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:15:23.258361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.94339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:15:23.258401Z","caller":"traceutil/trace.go:171","msg":"trace[1027882098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"843.012914ms","start":"2026-05-19T22:15:22.415377Z","end":"2026-05-19T22:15:23.25839Z","steps":["trace[1027882098] 'agreement among raft nodes before linearized reading' (duration: 842.952761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:15:23.258439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:15:22.415346Z","time spent":"843.087487ms","remote":"127.0.0.1:47400","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-05-19T22:15:23.258545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.923918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T22:15:23.258579Z","caller":"traceutil/trace.go:171","msg":"trace[2119597361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"741.026042ms","start":"2026-05-19T22:15:22.517543Z","end":"2026-05-19T22:15:23.258569Z","steps":["trace[2119597361] 'agreement among raft nodes before linearized reading' (duration: 740.798823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:15:23.258658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:15:22.517532Z","time spent":"741.117306ms","remote":"127.0.0.1:47690","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-05-19T22:15:23.258764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.970321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T22:15:23.258797Z","caller":"traceutil/trace.go:171","msg":"trace[822595778] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"657.036013ms","start":"2026-05-19T22:15:22.601754Z","end":"2026-05-19T22:15:23.25879Z","steps":["trace[822595778] 'agreement among raft nodes before linearized reading' (duration: 656.918998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:15:23.258827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:15:22.60173Z","time spent":"657.091805ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T22:15:23.258814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.777571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-19T22:15:23.258879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.475068ms","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-05-19T22:15:23.258907Z","caller":"traceutil/trace.go:171","msg":"trace[1504035385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"328.918547ms","start":"2026-05-19T22:15:22.929966Z","end":"2026-05-19T22:15:23.258885Z","steps":["trace[1504035385] 'agreement among raft nodes before linearized reading' (duration: 328.679537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:15:23.258941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:15:22.929933Z","time spent":"329.00133ms","remote":"127.0.0.1:47690","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":"info","ts":"2026-05-19T22:15:23.258945Z","caller":"traceutil/trace.go:171","msg":"trace[2035776156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"740.556782ms","start":"2026-05-19T22:15:22.51837Z","end":"2026-05-19T22:15:23.258927Z","steps":["trace[2035776156] 'agreement among raft nodes before linearized reading' (duration: 740.422596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:15:23.258986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:15:22.518362Z","time spent":"740.614794ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T22:15:25.110057Z","caller":"traceutil/trace.go:171","msg":"trace[1768012956] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"116.115669ms","start":"2026-05-19T22:15:24.99391Z","end":"2026-05-19T22:15:25.110026Z","steps":["trace[1768012956] 'process raft request' (duration: 115.927261ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:15:38.904202Z","caller":"traceutil/trace.go:171","msg":"trace[1567481293] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"487.611966ms","start":"2026-05-19T22:15:38.416569Z","end":"2026-05-19T22:15:38.904181Z","steps":["trace[1567481293] 'read index received' (duration: 487.417899ms)","trace[1567481293] 'applied index is now lower than readState.Index' (duration: 193.187µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:15:38.904271Z","caller":"traceutil/trace.go:171","msg":"trace[35913894] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"957.058694ms","start":"2026-05-19T22:15:37.947183Z","end":"2026-05-19T22:15:38.904242Z","steps":["trace[35913894] 'process raft request' (duration: 956.855397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:15:38.904361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.770622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:15:38.904401Z","caller":"traceutil/trace.go:171","msg":"trace[877483142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"487.872945ms","start":"2026-05-19T22:15:38.416519Z","end":"2026-05-19T22:15:38.904392Z","steps":["trace[877483142] 'agreement among raft nodes before linearized reading' (duration: 487.786432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:15:38.904437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:15:38.416501Z","time spent":"487.927967ms","remote":"127.0.0.1:47412","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-05-19T22:15:38.904537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:15:37.947164Z","time spent":"957.21333ms","remote":"127.0.0.1:47602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:15:38.904887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.044202ms","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-05-19T22:15:38.905042Z","caller":"traceutil/trace.go:171","msg":"trace[300056506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:782; }","duration":"256.241289ms","start":"2026-05-19T22:15:38.648781Z","end":"2026-05-19T22:15:38.905022Z","steps":["trace[300056506] 'agreement among raft nodes before linearized reading' (duration: 255.878806ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:16:13.226702Z","caller":"traceutil/trace.go:171","msg":"trace[1174793654] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"125.497885ms","start":"2026-05-19T22:16:13.101182Z","end":"2026-05-19T22:16:13.226679Z","steps":["trace[1174793654] 'process raft request' (duration: 125.371702ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:16:13.243977Z","caller":"traceutil/trace.go:171","msg":"trace[1926935698] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"139.264976ms","start":"2026-05-19T22:16:13.104696Z","end":"2026-05-19T22:16:13.243961Z","steps":["trace[1926935698] 'process raft request' (duration: 137.553934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:16:30.629992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.847729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:16:30.630153Z","caller":"traceutil/trace.go:171","msg":"trace[1948700981] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1348; }","duration":"138.106045ms","start":"2026-05-19T22:16:30.492015Z","end":"2026-05-19T22:16:30.630121Z","steps":["trace[1948700981] 'count revisions from in-memory index tree' (duration: 137.713926ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:16:36.407991Z","caller":"traceutil/trace.go:171","msg":"trace[214361850] transaction","detail":"{read_only:false; response_revision:1466; number_of_response:1; }","duration":"191.253381ms","start":"2026-05-19T22:16:36.216712Z","end":"2026-05-19T22:16:36.407965Z","steps":["trace[214361850] 'process raft request' (duration: 191.092118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:17:01.220564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.444416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:17:01.220693Z","caller":"traceutil/trace.go:171","msg":"trace[236875614] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1948; }","duration":"292.509082ms","start":"2026-05-19T22:17:00.928165Z","end":"2026-05-19T22:17:01.220674Z","steps":["trace[236875614] 'read index received' (duration: 88.770981ms)","trace[236875614] 'applied index is now lower than readState.Index' (duration: 203.736681ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:17:01.220908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.742076ms","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-05-19T22:17:01.220991Z","caller":"traceutil/trace.go:171","msg":"trace[363894248] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1870; }","duration":"292.844107ms","start":"2026-05-19T22:17:00.928129Z","end":"2026-05-19T22:17:01.220974Z","steps":["trace[363894248] 'agreement among raft nodes before linearized reading' (duration: 292.614473ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:17:01.221089Z","caller":"traceutil/trace.go:171","msg":"trace[1654518498] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"340.340026ms","start":"2026-05-19T22:17:00.880712Z","end":"2026-05-19T22:17:01.221052Z","steps":["trace[1654518498] 'process raft request' (duration: 136.292659ms)","trace[1654518498] 'compare' (duration: 203.178732ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:17:01.221242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:17:00.880687Z","time spent":"340.478348ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:17:01.453343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.070361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-58d25fe4-2c6d-4dee-84e9-16aed9b94435\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-19T22:17:01.453457Z","caller":"traceutil/trace.go:171","msg":"trace[1756376564] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-58d25fe4-2c6d-4dee-84e9-16aed9b94435; range_end:; response_count:1; response_revision:1871; }","duration":"124.194453ms","start":"2026-05-19T22:17:01.329207Z","end":"2026-05-19T22:17:01.453402Z","steps":["trace[1756376564] 'range keys from in-memory index tree' (duration: 123.909308ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:17:01.724307Z","caller":"traceutil/trace.go:171","msg":"trace[702685973] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"262.958969ms","start":"2026-05-19T22:17:01.461314Z","end":"2026-05-19T22:17:01.724273Z","steps":["trace[702685973] 'process raft request' (duration: 262.754606ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:17:02.250366Z","caller":"traceutil/trace.go:171","msg":"trace[1686417108] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"767.264871ms","start":"2026-05-19T22:17:01.483064Z","end":"2026-05-19T22:17:02.250329Z","steps":["trace[1686417108] 'process raft request' (duration: 730.098044ms)","trace[1686417108] 'compare' (duration: 36.613657ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:17:02.250466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.399613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:17:02.25051Z","caller":"traceutil/trace.go:171","msg":"trace[1812734793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1874; }","duration":"761.484074ms","start":"2026-05-19T22:17:01.489013Z","end":"2026-05-19T22:17:02.250497Z","steps":["trace[1812734793] 'agreement among raft nodes before linearized reading' (duration: 761.405303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:17:02.250517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:17:01.483035Z","time spent":"767.405413ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:17:02.250604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:17:01.488997Z","time spent":"761.562345ms","remote":"127.0.0.1:47412","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-05-19T22:17:02.250374Z","caller":"traceutil/trace.go:171","msg":"trace[1820611447] linearizableReadLoop","detail":"{readStateIndex:1952; appliedIndex:1950; }","duration":"761.285671ms","start":"2026-05-19T22:17:01.489051Z","end":"2026-05-19T22:17:02.250337Z","steps":["trace[1820611447] 'read index received' (duration: 234.961272ms)","trace[1820611447] 'applied index is now lower than readState.Index' (duration: 526.323219ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:17:02.250378Z","caller":"traceutil/trace.go:171","msg":"trace[1903463952] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"612.796322ms","start":"2026-05-19T22:17:01.637561Z","end":"2026-05-19T22:17:02.250358Z","steps":["trace[1903463952] 'process raft request' (duration: 612.6917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:17:02.250775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:17:01.637531Z","time spent":"613.194099ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:17:02.250974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.804381ms","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-05-19T22:17:02.251043Z","caller":"traceutil/trace.go:171","msg":"trace[1797842169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1874; }","duration":"455.904933ms","start":"2026-05-19T22:17:01.795126Z","end":"2026-05-19T22:17:02.251031Z","steps":["trace[1797842169] 'agreement among raft nodes before linearized reading' (duration: 455.770001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:17:02.251133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:17:01.795104Z","time spent":"455.988824ms","remote":"127.0.0.1:47690","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-05-19T22:17:02.250983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.974201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T22:17:02.25128Z","caller":"traceutil/trace.go:171","msg":"trace[1981522537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1874; }","duration":"454.269076ms","start":"2026-05-19T22:17:01.796992Z","end":"2026-05-19T22:17:02.251261Z","steps":["trace[1981522537] 'agreement among raft nodes before linearized reading' (duration: 453.815788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:17:02.25135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.065728ms","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-05-19T22:17:02.251434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:17:01.796943Z","time spent":"454.433528ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T22:17:02.251397Z","caller":"traceutil/trace.go:171","msg":"trace[326267156] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1874; }","duration":"452.221901ms","start":"2026-05-19T22:17:01.799161Z","end":"2026-05-19T22:17:02.251382Z","steps":["trace[326267156] 'agreement among raft nodes before linearized reading' (duration: 451.864785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:17:02.250961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.115328ms","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-05-19T22:17:02.25168Z","caller":"traceutil/trace.go:171","msg":"trace[569343101] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1874; }","duration":"758.895111ms","start":"2026-05-19T22:17:01.492771Z","end":"2026-05-19T22:17:02.251666Z","steps":["trace[569343101] 'agreement among raft nodes before linearized reading' (duration: 757.984926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:17:02.251752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:17:01.492752Z","time spent":"758.985043ms","remote":"127.0.0.1:47690","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-05-19T22:17:02.25158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:17:01.799057Z","time spent":"452.511236ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T22:17:02.251071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.287296ms","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-05-19T22:17:02.252071Z","caller":"traceutil/trace.go:171","msg":"trace[1132302298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1874; }","duration":"455.306673ms","start":"2026-05-19T22:17:01.796752Z","end":"2026-05-19T22:17:02.252058Z","steps":["trace[1132302298] 'agreement among raft nodes before linearized reading' (duration: 454.221465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:17:02.252134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:17:01.796742Z","time spent":"455.380244ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T22:17:02.252261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.515446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-19T22:17:02.252337Z","caller":"traceutil/trace.go:171","msg":"trace[2008429792] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1874; }","duration":"685.594207ms","start":"2026-05-19T22:17:01.566723Z","end":"2026-05-19T22:17:02.252317Z","steps":["trace[2008429792] 'agreement among raft nodes before linearized reading' (duration: 684.304926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:17:02.25239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:17:01.566663Z","time spent":"685.713799ms","remote":"127.0.0.1:54270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-19T22:17:30.693762Z","caller":"traceutil/trace.go:171","msg":"trace[1664486375] linearizableReadLoop","detail":"{readStateIndex:2174; appliedIndex:2173; }","duration":"244.489693ms","start":"2026-05-19T22:17:30.44924Z","end":"2026-05-19T22:17:30.69373Z","steps":["trace[1664486375] 'read index received' (duration: 211.485318ms)","trace[1664486375] 'applied index is now lower than readState.Index' (duration: 33.002965ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:17:30.693849Z","caller":"traceutil/trace.go:171","msg":"trace[1351800229] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"259.787148ms","start":"2026-05-19T22:17:30.434004Z","end":"2026-05-19T22:17:30.693792Z","steps":["trace[1351800229] 'process raft request' (duration: 226.703452ms)","trace[1351800229] 'compare' (duration: 32.846412ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:17:30.694096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.010915ms","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-05-19T22:17:30.694195Z","caller":"traceutil/trace.go:171","msg":"trace[787454692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2088; }","duration":"256.125867ms","start":"2026-05-19T22:17:30.438038Z","end":"2026-05-19T22:17:30.694164Z","steps":["trace[787454692] 'agreement among raft nodes before linearized reading' (duration: 255.817543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:17:30.694535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.781502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:17:30.694611Z","caller":"traceutil/trace.go:171","msg":"trace[1626072613] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2088; }","duration":"255.875883ms","start":"2026-05-19T22:17:30.438703Z","end":"2026-05-19T22:17:30.694579Z","steps":["trace[1626072613] 'agreement among raft nodes before linearized reading' (duration: 255.094962ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:17:30.745367Z","caller":"traceutil/trace.go:171","msg":"trace[1814241773] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"290.720284ms","start":"2026-05-19T22:17:30.454574Z","end":"2026-05-19T22:17:30.745294Z","steps":["trace[1814241773] 'process raft request' (duration: 290.566912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:17:30.745609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.26149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-19T22:17:30.745686Z","caller":"traceutil/trace.go:171","msg":"trace[1642721989] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2089; }","duration":"293.434692ms","start":"2026-05-19T22:17:30.452231Z","end":"2026-05-19T22:17:30.745666Z","steps":["trace[1642721989] 'agreement among raft nodes before linearized reading' (duration: 293.059797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:17:36.729082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.642045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T22:17:36.72917Z","caller":"traceutil/trace.go:171","msg":"trace[366928683] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2119; }","duration":"226.501747ms","start":"2026-05-19T22:17:36.502646Z","end":"2026-05-19T22:17:36.729148Z","steps":["trace[366928683] 'count revisions from in-memory index tree' (duration: 225.521034ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:17:37.51213Z","caller":"traceutil/trace.go:171","msg":"trace[1412724487] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"139.238014ms","start":"2026-05-19T22:17:37.37284Z","end":"2026-05-19T22:17:37.512078Z","steps":["trace[1412724487] 'process raft request' (duration: 139.043222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:17:38.050375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.459914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:17:38.050499Z","caller":"traceutil/trace.go:171","msg":"trace[430271610] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2211; }","duration":"230.540444ms","start":"2026-05-19T22:17:37.819938Z","end":"2026-05-19T22:17:38.050478Z","steps":["trace[430271610] 'read index received' (duration: 66.271µs)","trace[430271610] 'applied index is now lower than readState.Index' (duration: 230.472733ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:17:38.050701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.765456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-19T22:17:38.05074Z","caller":"traceutil/trace.go:171","msg":"trace[1714749288] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2125; }","duration":"230.840058ms","start":"2026-05-19T22:17:37.819884Z","end":"2026-05-19T22:17:38.050724Z","steps":["trace[1714749288] 'agreement among raft nodes before linearized reading' (duration: 230.648965ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:17:38.050806Z","caller":"traceutil/trace.go:171","msg":"trace[1200427820] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"533.111713ms","start":"2026-05-19T22:17:37.517457Z","end":"2026-05-19T22:17:38.050569Z","steps":["trace[1200427820] 'process raft request' (duration: 105.356585ms)","trace[1200427820] 'compare' (duration: 426.240767ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:17:38.051004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:17:37.517439Z","time spent":"533.474718ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:18:38.773189Z","caller":"traceutil/trace.go:171","msg":"trace[1734434052] transaction","detail":"{read_only:false; response_revision:2554; number_of_response:1; }","duration":"107.495185ms","start":"2026-05-19T22:18:38.665664Z","end":"2026-05-19T22:18:38.773159Z","steps":["trace[1734434052] 'process raft request' (duration: 107.333173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:19:11.503042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.906818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:19:11.503172Z","caller":"traceutil/trace.go:171","msg":"trace[424743478] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"205.435075ms","start":"2026-05-19T22:19:11.297713Z","end":"2026-05-19T22:19:11.503148Z","steps":["trace[424743478] 'compare' (duration: 202.767656ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:12.066284Z","caller":"traceutil/trace.go:171","msg":"trace[509862614] transaction","detail":"{read_only:false; response_revision:2739; number_of_response:1; }","duration":"179.475302ms","start":"2026-05-19T22:19:11.886774Z","end":"2026-05-19T22:19:12.066249Z","steps":["trace[509862614] 'process raft request' (duration: 137.153979ms)","trace[509862614] 'compare' (duration: 40.897598ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:19:17.565419Z","caller":"traceutil/trace.go:171","msg":"trace[625929901] linearizableReadLoop","detail":"{readStateIndex:2876; appliedIndex:2875; }","duration":"330.854442ms","start":"2026-05-19T22:19:17.234532Z","end":"2026-05-19T22:19:17.565386Z","steps":["trace[625929901] 'read index received' (duration: 330.558099ms)","trace[625929901] 'applied index is now lower than readState.Index' (duration: 295.353µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:19:17.566054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.36538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T22:19:17.566102Z","caller":"traceutil/trace.go:171","msg":"trace[607901174] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2766; }","duration":"291.468852ms","start":"2026-05-19T22:19:17.27462Z","end":"2026-05-19T22:19:17.566089Z","steps":["trace[607901174] 'agreement among raft nodes before linearized reading' (duration: 291.27464ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:17.56637Z","caller":"traceutil/trace.go:171","msg":"trace[1579873090] transaction","detail":"{read_only:false; response_revision:2766; number_of_response:1; }","duration":"344.12662ms","start":"2026-05-19T22:19:17.222232Z","end":"2026-05-19T22:19:17.566359Z","steps":["trace[1579873090] 'process raft request' (duration: 342.949208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:19:17.566517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:19:17.222207Z","time spent":"344.19326ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:19:17.568537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.692208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:19:17.568576Z","caller":"traceutil/trace.go:171","msg":"trace[1928907616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2766; }","duration":"152.770829ms","start":"2026-05-19T22:19:17.415795Z","end":"2026-05-19T22:19:17.568566Z","steps":["trace[1928907616] 'agreement among raft nodes before linearized reading' (duration: 152.704638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:19:17.568828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.303478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-19T22:19:17.568856Z","caller":"traceutil/trace.go:171","msg":"trace[1072021691] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2766; }","duration":"334.332238ms","start":"2026-05-19T22:19:17.234516Z","end":"2026-05-19T22:19:17.568848Z","steps":["trace[1072021691] 'agreement among raft nodes before linearized reading' (duration: 334.249217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:19:17.568876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:19:17.234462Z","time spent":"334.409569ms","remote":"127.0.0.1:47604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-19T22:19:17.569115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.313815ms","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-05-19T22:19:17.569202Z","caller":"traceutil/trace.go:171","msg":"trace[2007082870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2766; }","duration":"254.445426ms","start":"2026-05-19T22:19:17.314736Z","end":"2026-05-19T22:19:17.569182Z","steps":["trace[2007082870] 'agreement among raft nodes before linearized reading' (duration: 254.252324ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:17.677333Z","caller":"traceutil/trace.go:171","msg":"trace[1541732806] transaction","detail":"{read_only:false; response_revision:2768; number_of_response:1; }","duration":"104.655499ms","start":"2026-05-19T22:19:17.572646Z","end":"2026-05-19T22:19:17.677302Z","steps":["trace[1541732806] 'process raft request' (duration: 103.556788ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:17.677557Z","caller":"traceutil/trace.go:171","msg":"trace[279542064] transaction","detail":"{read_only:false; response_revision:2769; number_of_response:1; }","duration":"102.868371ms","start":"2026-05-19T22:19:17.574671Z","end":"2026-05-19T22:19:17.67754Z","steps":["trace[279542064] 'process raft request' (duration: 102.566357ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:17.677681Z","caller":"traceutil/trace.go:171","msg":"trace[1222120996] transaction","detail":"{read_only:false; response_revision:2770; number_of_response:1; }","duration":"102.939511ms","start":"2026-05-19T22:19:17.574737Z","end":"2026-05-19T22:19:17.677677Z","steps":["trace[1222120996] 'process raft request' (duration: 102.524397ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:10.283848Z","caller":"traceutil/trace.go:171","msg":"trace[913556946] transaction","detail":"{read_only:false; response_revision:3043; number_of_response:1; }","duration":"221.43468ms","start":"2026-05-19T22:20:10.062388Z","end":"2026-05-19T22:20:10.283822Z","steps":["trace[913556946] 'process raft request' (duration: 221.257584ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:10.343304Z","caller":"traceutil/trace.go:171","msg":"trace[544739295] transaction","detail":"{read_only:false; response_revision:3045; number_of_response:1; }","duration":"280.696385ms","start":"2026-05-19T22:20:10.062583Z","end":"2026-05-19T22:20:10.343279Z","steps":["trace[544739295] 'process raft request' (duration: 280.579432ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:20:10.343357Z","caller":"traceutil/trace.go:171","msg":"trace[1173740257] transaction","detail":"{read_only:false; response_revision:3044; number_of_response:1; }","duration":"280.83121ms","start":"2026-05-19T22:20:10.062504Z","end":"2026-05-19T22:20:10.343335Z","steps":["trace[1173740257] 'process raft request' (duration: 277.135352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:09.34071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.726393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:21:09.340963Z","caller":"traceutil/trace.go:171","msg":"trace[1002101369] transaction","detail":"{read_only:false; response_revision:3395; number_of_response:1; }","duration":"157.723612ms","start":"2026-05-19T22:21:09.183182Z","end":"2026-05-19T22:21:09.340906Z","steps":["trace[1002101369] 'process raft request' (duration: 30.711363ms)","trace[1002101369] 'compare' (duration: 126.58855ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:21:09.34103Z","caller":"traceutil/trace.go:171","msg":"trace[220351827] transaction","detail":"{read_only:false; response_revision:3396; number_of_response:1; }","duration":"157.583839ms","start":"2026-05-19T22:21:09.183403Z","end":"2026-05-19T22:21:09.340987Z","steps":["trace[220351827] 'process raft request' (duration: 157.413175ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:09.341231Z","caller":"traceutil/trace.go:171","msg":"trace[701818625] linearizableReadLoop","detail":"{readStateIndex:3533; appliedIndex:3531; }","duration":"157.628031ms","start":"2026-05-19T22:21:09.183589Z","end":"2026-05-19T22:21:09.341217Z","steps":["trace[701818625] 'read index received' (duration: 30.235803ms)","trace[701818625] 'applied index is now lower than readState.Index' (duration: 127.390667ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:21:09.341104Z","caller":"traceutil/trace.go:171","msg":"trace[733614273] transaction","detail":"{read_only:false; response_revision:3397; number_of_response:1; }","duration":"156.031113ms","start":"2026-05-19T22:21:09.185Z","end":"2026-05-19T22:21:09.341031Z","steps":["trace[733614273] 'process raft request' (duration: 155.87043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:09.341342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.749943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/passwords.secretgen.k14s.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:21:09.341398Z","caller":"traceutil/trace.go:171","msg":"trace[835389865] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/passwords.secretgen.k14s.io; range_end:; response_count:0; response_revision:3397; }","duration":"157.855175ms","start":"2026-05-19T22:21:09.18353Z","end":"2026-05-19T22:21:09.341385Z","steps":["trace[835389865] 'agreement among raft nodes before linearized reading' (duration: 157.753113ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:10.709402Z","caller":"traceutil/trace.go:171","msg":"trace[1645480943] linearizableReadLoop","detail":"{readStateIndex:3573; appliedIndex:3572; }","duration":"116.410777ms","start":"2026-05-19T22:21:10.592969Z","end":"2026-05-19T22:21:10.70938Z","steps":["trace[1645480943] 'read index received' (duration: 116.207643ms)","trace[1645480943] 'applied index is now lower than readState.Index' (duration: 201.954µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:21:10.709569Z","caller":"traceutil/trace.go:171","msg":"trace[344232490] transaction","detail":"{read_only:false; response_revision:3435; number_of_response:1; }","duration":"149.451582ms","start":"2026-05-19T22:21:10.560104Z","end":"2026-05-19T22:21:10.709555Z","steps":["trace[344232490] 'process raft request' (duration: 149.144005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:10.709982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.983601ms","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-05-19T22:21:10.710088Z","caller":"traceutil/trace.go:171","msg":"trace[1680285107] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3435; }","duration":"117.151504ms","start":"2026-05-19T22:21:10.5929Z","end":"2026-05-19T22:21:10.710051Z","steps":["trace[1680285107] 'agreement among raft nodes before linearized reading' (duration: 116.93773ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:25.60056Z","caller":"traceutil/trace.go:171","msg":"trace[1583080857] transaction","detail":"{read_only:false; response_revision:3522; number_of_response:1; }","duration":"131.06713ms","start":"2026-05-19T22:21:25.469436Z","end":"2026-05-19T22:21:25.600503Z","steps":["trace[1583080857] 'process raft request' (duration: 130.716083ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:55.133542Z","caller":"traceutil/trace.go:171","msg":"trace[1346672300] transaction","detail":"{read_only:false; response_revision:3682; number_of_response:1; }","duration":"130.068215ms","start":"2026-05-19T22:21:55.003439Z","end":"2026-05-19T22:21:55.133508Z","steps":["trace[1346672300] 'process raft request' (duration: 129.864881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:06.595227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.217373ms","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:4737"} {"level":"info","ts":"2026-05-19T22:23:06.595306Z","caller":"traceutil/trace.go:171","msg":"trace[16800256] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4565; }","duration":"102.306595ms","start":"2026-05-19T22:23:06.492981Z","end":"2026-05-19T22:23:06.595288Z","steps":["trace[16800256] 'range keys from in-memory index tree' (duration: 102.05517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:06.595652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.373305ms","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-05-19T22:23:06.595741Z","caller":"traceutil/trace.go:171","msg":"trace[16633018] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4565; }","duration":"102.515178ms","start":"2026-05-19T22:23:06.493207Z","end":"2026-05-19T22:23:06.595722Z","steps":["trace[16633018] 'range keys from in-memory index tree' (duration: 102.149391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:09.33641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.031669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:57151"} {"level":"info","ts":"2026-05-19T22:23:09.336524Z","caller":"traceutil/trace.go:171","msg":"trace[1017237042] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4642; }","duration":"108.208712ms","start":"2026-05-19T22:23:09.228295Z","end":"2026-05-19T22:23:09.336503Z","steps":["trace[1017237042] 'range keys from in-memory index tree' (duration: 107.745884ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:15.214847Z","caller":"traceutil/trace.go:171","msg":"trace[158535535] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"102.997707ms","start":"2026-05-19T22:23:15.111824Z","end":"2026-05-19T22:23:15.214822Z","steps":["trace[158535535] 'process raft request' (duration: 102.848214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:15.936851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.695798ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-19T22:23:15.937052Z","caller":"traceutil/trace.go:171","msg":"trace[336034399] linearizableReadLoop","detail":"{readStateIndex:4921; appliedIndex:4920; }","duration":"721.65645ms","start":"2026-05-19T22:23:15.21538Z","end":"2026-05-19T22:23:15.937037Z","steps":["trace[336034399] 'read index received' (duration: 348.784889ms)","trace[336034399] 'applied index is now lower than readState.Index' (duration: 372.869961ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:23:15.937174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.784972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T22:23:15.937227Z","caller":"traceutil/trace.go:171","msg":"trace[1247412328] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:4731; }","duration":"721.980345ms","start":"2026-05-19T22:23:15.215239Z","end":"2026-05-19T22:23:15.937219Z","steps":["trace[1247412328] 'agreement among raft nodes before linearized reading' (duration: 721.890743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:15.937272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:15.21522Z","time spent":"722.042676ms","remote":"127.0.0.1:47726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-19T22:23:15.98361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.498378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1579"} {"level":"warn","ts":"2026-05-19T22:23:15.983713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.851849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:23:15.983773Z","caller":"traceutil/trace.go:171","msg":"trace[1102012840] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4732; }","duration":"761.706672ms","start":"2026-05-19T22:23:15.22205Z","end":"2026-05-19T22:23:15.983756Z","steps":["trace[1102012840] 'agreement among raft nodes before linearized reading' (duration: 761.443348ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:15.983823Z","caller":"traceutil/trace.go:171","msg":"trace[686584533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4732; }","duration":"567.013162ms","start":"2026-05-19T22:23:15.416786Z","end":"2026-05-19T22:23:15.983799Z","steps":["trace[686584533] 'agreement among raft nodes before linearized reading' (duration: 566.832999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:15.98383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.521796ms","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-05-19T22:23:15.983886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:15.416766Z","time spent":"567.107533ms","remote":"127.0.0.1:47412","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-05-19T22:23:15.983949Z","caller":"traceutil/trace.go:171","msg":"trace[2142175832] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"463.75907ms","start":"2026-05-19T22:23:15.520181Z","end":"2026-05-19T22:23:15.98394Z","steps":["trace[2142175832] 'process raft request' (duration: 463.15303ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:15.983954Z","caller":"traceutil/trace.go:171","msg":"trace[1663884357] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4732; }","duration":"407.699558ms","start":"2026-05-19T22:23:15.576223Z","end":"2026-05-19T22:23:15.983923Z","steps":["trace[1663884357] 'agreement among raft nodes before linearized reading' (duration: 407.309942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:15.984025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:15.576205Z","time spent":"407.809209ms","remote":"127.0.0.1:47690","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-05-19T22:23:15.984042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.912718ms","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-05-19T22:23:15.984093Z","caller":"traceutil/trace.go:171","msg":"trace[1791886061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4732; }","duration":"357.981318ms","start":"2026-05-19T22:23:15.626093Z","end":"2026-05-19T22:23:15.984075Z","steps":["trace[1791886061] 'agreement among raft nodes before linearized reading' (duration: 357.705274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:15.983967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.848029ms","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-05-19T22:23:15.984202Z","caller":"traceutil/trace.go:171","msg":"trace[488170690] 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:4732; }","duration":"731.141044ms","start":"2026-05-19T22:23:15.253042Z","end":"2026-05-19T22:23:15.984183Z","steps":["trace[488170690] 'agreement among raft nodes before linearized reading' (duration: 730.584355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:15.984212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.27704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"warn","ts":"2026-05-19T22:23:15.984235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.394176ms","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-05-19T22:23:15.984256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:15.253024Z","time spent":"731.216975ms","remote":"127.0.0.1:47580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-05-19T22:23:15.984213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.139039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-784c9d7d64-fvgrx.18b117b510403a88\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-05-19T22:23:15.984283Z","caller":"traceutil/trace.go:171","msg":"trace[466179146] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4732; }","duration":"382.470298ms","start":"2026-05-19T22:23:15.601801Z","end":"2026-05-19T22:23:15.984271Z","steps":["trace[466179146] 'agreement among raft nodes before linearized reading' (duration: 382.180963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:15.984517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:15.601784Z","time spent":"382.723611ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T22:23:15.984532Z","caller":"traceutil/trace.go:171","msg":"trace[103210165] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-784c9d7d64-fvgrx.18b117b510403a88; range_end:; response_count:1; response_revision:4732; }","duration":"754.455793ms","start":"2026-05-19T22:23:15.23004Z","end":"2026-05-19T22:23:15.984496Z","steps":["trace[103210165] 'agreement among raft nodes before linearized reading' (duration: 753.521288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:15.983929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.750088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1579"} {"level":"info","ts":"2026-05-19T22:23:15.984655Z","caller":"traceutil/trace.go:171","msg":"trace[113622598] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4732; }","duration":"758.51637ms","start":"2026-05-19T22:23:15.226117Z","end":"2026-05-19T22:23:15.984634Z","steps":["trace[113622598] 'agreement among raft nodes before linearized reading' (duration: 757.653445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:15.984681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.940292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-05-19T22:23:15.984713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:15.2261Z","time spent":"758.597191ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":1602,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-05-19T22:23:15.984513Z","caller":"traceutil/trace.go:171","msg":"trace[719176543] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4732; }","duration":"754.366682ms","start":"2026-05-19T22:23:15.22988Z","end":"2026-05-19T22:23:15.984246Z","steps":["trace[719176543] 'agreement among raft nodes before linearized reading' (duration: 753.774743ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:15.984742Z","caller":"traceutil/trace.go:171","msg":"trace[1026881755] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4732; }","duration":"400.019713ms","start":"2026-05-19T22:23:15.584686Z","end":"2026-05-19T22:23:15.984706Z","steps":["trace[1026881755] 'agreement among raft nodes before linearized reading' (duration: 399.372172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:15.984818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:15.584667Z","time spent":"400.138495ms","remote":"127.0.0.1:47602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-19T22:23:15.984957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:15.229868Z","time spent":"754.943311ms","remote":"127.0.0.1:47602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12998,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-05-19T22:23:15.984097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:15.520153Z","time spent":"463.839312ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:23:15.983881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:15.222033Z","time spent":"761.815514ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":1602,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-05-19T22:23:15.98415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:15.626078Z","time spent":"358.058629ms","remote":"127.0.0.1:47690","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":"warn","ts":"2026-05-19T22:23:15.984603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:15.230025Z","time spent":"754.570765ms","remote":"127.0.0.1:47496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":919,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-784c9d7d64-fvgrx.18b117b510403a88\" "} {"level":"warn","ts":"2026-05-19T22:23:16.109446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.251082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-19T22:23:16.10953Z","caller":"traceutil/trace.go:171","msg":"trace[2106098392] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4739; }","duration":"118.378834ms","start":"2026-05-19T22:23:15.991132Z","end":"2026-05-19T22:23:16.109511Z","steps":["trace[2106098392] 'agreement among raft nodes before linearized reading' (duration: 118.1267ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:16.242537Z","caller":"traceutil/trace.go:171","msg":"trace[59812131] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"159.041685ms","start":"2026-05-19T22:23:16.08346Z","end":"2026-05-19T22:23:16.242501Z","steps":["trace[59812131] 'process raft request' (duration: 158.77798ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:16.2436Z","caller":"traceutil/trace.go:171","msg":"trace[155675972] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"159.570373ms","start":"2026-05-19T22:23:16.084007Z","end":"2026-05-19T22:23:16.243578Z","steps":["trace[155675972] 'process raft request' (duration: 158.385574ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:16.244609Z","caller":"traceutil/trace.go:171","msg":"trace[953570863] linearizableReadLoop","detail":"{readStateIndex:4933; appliedIndex:4929; }","duration":"130.940097ms","start":"2026-05-19T22:23:16.113648Z","end":"2026-05-19T22:23:16.244588Z","steps":["trace[953570863] 'read index received' (duration: 128.570349ms)","trace[953570863] 'applied index is now lower than readState.Index' (duration: 2.368738ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:23:16.24494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.269963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9425"} {"level":"info","ts":"2026-05-19T22:23:16.244175Z","caller":"traceutil/trace.go:171","msg":"trace[1218299459] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"159.097505ms","start":"2026-05-19T22:23:16.085034Z","end":"2026-05-19T22:23:16.244131Z","steps":["trace[1218299459] 'process raft request' (duration: 158.411404ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:16.245733Z","caller":"traceutil/trace.go:171","msg":"trace[244123451] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"160.562819ms","start":"2026-05-19T22:23:16.085156Z","end":"2026-05-19T22:23:16.245719Z","steps":["trace[244123451] 'process raft request' (duration: 158.384964ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:16.244987Z","caller":"traceutil/trace.go:171","msg":"trace[2075095810] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4743; }","duration":"131.411195ms","start":"2026-05-19T22:23:16.113564Z","end":"2026-05-19T22:23:16.244975Z","steps":["trace[2075095810] 'agreement among raft nodes before linearized reading' (duration: 131.09714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:17.128955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.931189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:23:17.129637Z","caller":"traceutil/trace.go:171","msg":"trace[742990947] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"416.432873ms","start":"2026-05-19T22:23:16.713185Z","end":"2026-05-19T22:23:17.129618Z","steps":["trace[742990947] 'process raft request' (duration: 416.141718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:17.12972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:16.713162Z","time spent":"416.522225ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:23:17.129766Z","caller":"traceutil/trace.go:171","msg":"trace[1068778296] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"941.648398ms","start":"2026-05-19T22:23:16.188071Z","end":"2026-05-19T22:23:17.12972Z","steps":["trace[1068778296] 'process raft request' (duration: 365.829525ms)","trace[1068778296] 'compare' (duration: 574.713985ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:23:17.129992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:16.188044Z","time spent":"941.827891ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2724,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:23:17.130067Z","caller":"traceutil/trace.go:171","msg":"trace[1347749192] linearizableReadLoop","detail":"{readStateIndex:4935; appliedIndex:4933; }","duration":"875.687157ms","start":"2026-05-19T22:23:16.254358Z","end":"2026-05-19T22:23:17.130045Z","steps":["trace[1347749192] 'read index received' (duration: 299.457867ms)","trace[1347749192] 'applied index is now lower than readState.Index' (duration: 576.22717ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:23:17.130184Z","caller":"traceutil/trace.go:171","msg":"trace[853821908] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"911.692572ms","start":"2026-05-19T22:23:16.218475Z","end":"2026-05-19T22:23:17.130168Z","steps":["trace[853821908] 'process raft request' (duration: 910.582784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:17.130245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:16.218443Z","time spent":"911.773743ms","remote":"127.0.0.1:47496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":925,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T22:23:17.130287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.862438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:23:17.130338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.964621ms","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-05-19T22:23:17.130367Z","caller":"traceutil/trace.go:171","msg":"trace[704424421] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4746; }","duration":"822.998541ms","start":"2026-05-19T22:23:16.307353Z","end":"2026-05-19T22:23:17.130352Z","steps":["trace[704424421] 'agreement among raft nodes before linearized reading' (duration: 822.873279ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:17.130387Z","caller":"traceutil/trace.go:171","msg":"trace[449786865] 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:4746; }","duration":"876.016442ms","start":"2026-05-19T22:23:16.254353Z","end":"2026-05-19T22:23:17.13037Z","steps":["trace[449786865] 'agreement among raft nodes before linearized reading' (duration: 875.828389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:17.130403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:16.307335Z","time spent":"823.062042ms","remote":"127.0.0.1:47862","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":"warn","ts":"2026-05-19T22:23:17.130434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:16.254334Z","time spent":"876.091023ms","remote":"127.0.0.1:47580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-05-19T22:23:17.130549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.741209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-19T22:23:17.130587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.095267ms","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-05-19T22:23:17.130579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.999232ms","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-05-19T22:23:17.130605Z","caller":"traceutil/trace.go:171","msg":"trace[1648518827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4746; }","duration":"803.163958ms","start":"2026-05-19T22:23:16.327435Z","end":"2026-05-19T22:23:17.130599Z","steps":["trace[1648518827] 'agreement among raft nodes before linearized reading' (duration: 803.099667ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:17.130602Z","caller":"traceutil/trace.go:171","msg":"trace[1851541979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4746; }","duration":"776.862451ms","start":"2026-05-19T22:23:16.353726Z","end":"2026-05-19T22:23:17.130588Z","steps":["trace[1851541979] 'agreement among raft nodes before linearized reading' (duration: 776.668698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:17.130624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:16.327391Z","time spent":"803.228849ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T22:23:17.130633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:16.353678Z","time spent":"776.948252ms","remote":"127.0.0.1:47690","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-05-19T22:23:17.130653Z","caller":"traceutil/trace.go:171","msg":"trace[120909435] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4746; }","duration":"777.080204ms","start":"2026-05-19T22:23:16.353531Z","end":"2026-05-19T22:23:17.130611Z","steps":["trace[120909435] 'agreement among raft nodes before linearized reading' (duration: 776.787049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:17.130699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:16.35351Z","time spent":"777.178965ms","remote":"127.0.0.1:47690","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":"info","ts":"2026-05-19T22:23:17.359019Z","caller":"traceutil/trace.go:171","msg":"trace[1651361230] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"222.988595ms","start":"2026-05-19T22:23:17.135999Z","end":"2026-05-19T22:23:17.358987Z","steps":["trace[1651361230] 'process raft request' (duration: 215.766008ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:17.361635Z","caller":"traceutil/trace.go:171","msg":"trace[1796524568] linearizableReadLoop","detail":"{readStateIndex:4939; appliedIndex:4936; }","duration":"224.784835ms","start":"2026-05-19T22:23:17.136833Z","end":"2026-05-19T22:23:17.361617Z","steps":["trace[1796524568] 'read index received' (duration: 214.948995ms)","trace[1796524568] 'applied index is now lower than readState.Index' (duration: 9.83491ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:23:17.362123Z","caller":"traceutil/trace.go:171","msg":"trace[1805866407] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"225.474846ms","start":"2026-05-19T22:23:17.136625Z","end":"2026-05-19T22:23:17.3621Z","steps":["trace[1805866407] 'process raft request' (duration: 224.948057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:17.362275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.435705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:23:17.362351Z","caller":"traceutil/trace.go:171","msg":"trace[1119372358] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:0; response_revision:4749; }","duration":"225.558418ms","start":"2026-05-19T22:23:17.136775Z","end":"2026-05-19T22:23:17.362333Z","steps":["trace[1119372358] 'agreement among raft nodes before linearized reading' (duration: 225.419295ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:17.361926Z","caller":"traceutil/trace.go:171","msg":"trace[526934320] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"225.754711ms","start":"2026-05-19T22:23:17.136146Z","end":"2026-05-19T22:23:17.361901Z","steps":["trace[526934320] 'process raft request' (duration: 225.350134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:17.362723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.222795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-19T22:23:17.362829Z","caller":"traceutil/trace.go:171","msg":"trace[709887845] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4749; }","duration":"224.357578ms","start":"2026-05-19T22:23:17.138449Z","end":"2026-05-19T22:23:17.362807Z","steps":["trace[709887845] 'agreement among raft nodes before linearized reading' (duration: 224.023353ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:25.51618Z","caller":"traceutil/trace.go:171","msg":"trace[1780293353] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"109.205595ms","start":"2026-05-19T22:23:25.406941Z","end":"2026-05-19T22:23:25.516147Z","steps":["trace[1780293353] 'process raft request' (duration: 102.802003ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:25.51625Z","caller":"traceutil/trace.go:171","msg":"trace[1314459567] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"108.761098ms","start":"2026-05-19T22:23:25.407438Z","end":"2026-05-19T22:23:25.516199Z","steps":["trace[1314459567] 'process raft request' (duration: 108.644916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:25.516438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.178035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f0577d30-2a64-4ebc-bfa9-938e6558870f\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-05-19T22:23:25.516472Z","caller":"traceutil/trace.go:171","msg":"trace[1707910189] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f0577d30-2a64-4ebc-bfa9-938e6558870f; range_end:; response_count:1; response_revision:4921; }","duration":"109.224566ms","start":"2026-05-19T22:23:25.407237Z","end":"2026-05-19T22:23:25.516461Z","steps":["trace[1707910189] 'agreement among raft nodes before linearized reading' (duration: 109.104054ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:25.516314Z","caller":"traceutil/trace.go:171","msg":"trace[353049553] linearizableReadLoop","detail":"{readStateIndex:5116; appliedIndex:5115; }","duration":"109.049412ms","start":"2026-05-19T22:23:25.407242Z","end":"2026-05-19T22:23:25.516291Z","steps":["trace[353049553] 'read index received' (duration: 102.400966ms)","trace[353049553] 'applied index is now lower than readState.Index' (duration: 6.647556ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:23:25.516703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.204076ms","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-05-19T22:23:25.516714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.593449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:23:25.51682Z","caller":"traceutil/trace.go:171","msg":"trace[640819650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4921; }","duration":"102.694931ms","start":"2026-05-19T22:23:25.414078Z","end":"2026-05-19T22:23:25.516773Z","steps":["trace[640819650] 'agreement among raft nodes before linearized reading' (duration: 102.549259ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:25.516745Z","caller":"traceutil/trace.go:171","msg":"trace[1763950626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4921; }","duration":"109.251956ms","start":"2026-05-19T22:23:25.407474Z","end":"2026-05-19T22:23:25.516726Z","steps":["trace[1763950626] 'agreement among raft nodes before linearized reading' (duration: 109.167405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:25.516879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.377768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13878"} {"level":"info","ts":"2026-05-19T22:23:25.5172Z","caller":"traceutil/trace.go:171","msg":"trace[1358875085] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4921; }","duration":"109.804385ms","start":"2026-05-19T22:23:25.407376Z","end":"2026-05-19T22:23:25.517181Z","steps":["trace[1358875085] 'agreement among raft nodes before linearized reading' (duration: 109.239646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:30.606487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.540509ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:23:30.606603Z","caller":"traceutil/trace.go:171","msg":"trace[1504701241] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4959; }","duration":"279.688562ms","start":"2026-05-19T22:23:30.326896Z","end":"2026-05-19T22:23:30.606585Z","steps":["trace[1504701241] 'range keys from in-memory index tree' (duration: 279.521369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:30.60764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.554196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:23:30.607826Z","caller":"traceutil/trace.go:171","msg":"trace[1773700291] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"1.18487171s","start":"2026-05-19T22:23:29.422924Z","end":"2026-05-19T22:23:30.607796Z","steps":["trace[1773700291] 'process raft request' (duration: 560.08353ms)","trace[1773700291] 'compare' (duration: 624.409704ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:23:30.608138Z","caller":"traceutil/trace.go:171","msg":"trace[1108117650] linearizableReadLoop","detail":"{readStateIndex:5156; appliedIndex:5155; }","duration":"1.10420427s","start":"2026-05-19T22:23:29.50391Z","end":"2026-05-19T22:23:30.608115Z","steps":["trace[1108117650] 'read index received' (duration: 479.079164ms)","trace[1108117650] 'applied index is now lower than readState.Index' (duration: 625.123406ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:23:30.608228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104321601s","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-05-19T22:23:30.608253Z","caller":"traceutil/trace.go:171","msg":"trace[1103399048] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:4962; }","duration":"1.104349912s","start":"2026-05-19T22:23:29.503893Z","end":"2026-05-19T22:23:30.608243Z","steps":["trace[1103399048] 'agreement among raft nodes before linearized reading' (duration: 1.104297641s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:30.608277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:29.503837Z","time spent":"1.104433713s","remote":"127.0.0.1:44848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"info","ts":"2026-05-19T22:23:30.608375Z","caller":"traceutil/trace.go:171","msg":"trace[1855617627] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"670.043128ms","start":"2026-05-19T22:23:29.938297Z","end":"2026-05-19T22:23:30.60834Z","steps":["trace[1855617627] 'process raft request' (duration: 669.546261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:30.608474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:29.938269Z","time spent":"670.159231ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:23:30.608541Z","caller":"traceutil/trace.go:171","msg":"trace[1575715683] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"572.023954ms","start":"2026-05-19T22:23:30.036205Z","end":"2026-05-19T22:23:30.608229Z","steps":["trace[1575715683] 'process raft request' (duration: 571.907692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:30.608069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:29.422907Z","time spent":"1.184980941s","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:23:30.608739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:30.036175Z","time spent":"572.466751ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:23:30.771478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.251564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:23:30.77153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.685699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T22:23:30.771544Z","caller":"traceutil/trace.go:171","msg":"trace[367452021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4963; }","duration":"356.391947ms","start":"2026-05-19T22:23:30.415141Z","end":"2026-05-19T22:23:30.771533Z","steps":["trace[367452021] 'agreement among raft nodes before linearized reading' (duration: 356.243805ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:30.771578Z","caller":"traceutil/trace.go:171","msg":"trace[1760690161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4963; }","duration":"631.7746ms","start":"2026-05-19T22:23:30.139791Z","end":"2026-05-19T22:23:30.771566Z","steps":["trace[1760690161] 'agreement among raft nodes before linearized reading' (duration: 631.576367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:30.771582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:30.415126Z","time spent":"356.448338ms","remote":"127.0.0.1:47412","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-05-19T22:23:30.771611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:30.139772Z","time spent":"631.831581ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T22:23:30.771807Z","caller":"traceutil/trace.go:171","msg":"trace[104104547] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"619.828951ms","start":"2026-05-19T22:23:30.151963Z","end":"2026-05-19T22:23:30.771792Z","steps":["trace[104104547] 'process raft request' (duration: 593.738738ms)","trace[104104547] 'compare' (duration: 25.464713ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:23:30.77192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:30.151939Z","time spent":"619.903752ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:23:30.771911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.233423ms","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-05-19T22:23:30.771981Z","caller":"traceutil/trace.go:171","msg":"trace[1743330117] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4963; }","duration":"373.374826ms","start":"2026-05-19T22:23:30.398587Z","end":"2026-05-19T22:23:30.771962Z","steps":["trace[1743330117] 'agreement among raft nodes before linearized reading' (duration: 373.129022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:30.772037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:30.398559Z","time spent":"373.468678ms","remote":"127.0.0.1:47580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-05-19T22:23:30.771978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.401486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T22:23:30.772118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.00343ms","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-05-19T22:23:30.772137Z","caller":"traceutil/trace.go:171","msg":"trace[1890976068] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:4963; }","duration":"366.66539ms","start":"2026-05-19T22:23:30.40542Z","end":"2026-05-19T22:23:30.772086Z","steps":["trace[1890976068] 'agreement among raft nodes before linearized reading' (duration: 366.332704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:30.771965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.439541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-19T22:23:30.772232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:30.405356Z","time spent":"366.859953ms","remote":"127.0.0.1:47580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":4,"response size":30,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-05-19T22:23:30.77216Z","caller":"traceutil/trace.go:171","msg":"trace[125863716] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:4963; }","duration":"255.096092ms","start":"2026-05-19T22:23:30.517053Z","end":"2026-05-19T22:23:30.772149Z","steps":["trace[125863716] 'agreement among raft nodes before linearized reading' (duration: 254.99075ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:30.77227Z","caller":"traceutil/trace.go:171","msg":"trace[1414619841] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4963; }","duration":"620.741436ms","start":"2026-05-19T22:23:30.151475Z","end":"2026-05-19T22:23:30.772216Z","steps":["trace[1414619841] 'agreement among raft nodes before linearized reading' (duration: 620.38417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:30.772404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:30.151456Z","time spent":"620.936969ms","remote":"127.0.0.1:47690","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-05-19T22:23:30.772416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.91588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-19T22:23:30.772451Z","caller":"traceutil/trace.go:171","msg":"trace[1525321090] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:4963; }","duration":"852.005301ms","start":"2026-05-19T22:23:29.920436Z","end":"2026-05-19T22:23:30.772441Z","steps":["trace[1525321090] 'agreement among raft nodes before linearized reading' (duration: 851.90228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:30.772479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:29.920409Z","time spent":"852.063363ms","remote":"127.0.0.1:47430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"warn","ts":"2026-05-19T22:23:30.772546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.306301ms","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-05-19T22:23:30.772566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.048126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:23:30.772597Z","caller":"traceutil/trace.go:171","msg":"trace[1734820545] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:4963; }","duration":"788.084068ms","start":"2026-05-19T22:23:29.984504Z","end":"2026-05-19T22:23:30.772589Z","steps":["trace[1734820545] 'agreement among raft nodes before linearized reading' (duration: 788.028477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:30.772622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:29.984412Z","time spent":"788.204019ms","remote":"127.0.0.1:47506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-05-19T22:23:30.772588Z","caller":"traceutil/trace.go:171","msg":"trace[2064888584] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4963; }","duration":"634.402593ms","start":"2026-05-19T22:23:30.138175Z","end":"2026-05-19T22:23:30.772578Z","steps":["trace[2064888584] 'agreement among raft nodes before linearized reading' (duration: 634.299561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:30.772676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:30.13815Z","time spent":"634.515604ms","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T22:23:30.77276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021630064s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:23:30.772791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234757247s","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-05-19T22:23:30.772802Z","caller":"traceutil/trace.go:171","msg":"trace[706468361] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:4963; }","duration":"1.021723816s","start":"2026-05-19T22:23:29.751067Z","end":"2026-05-19T22:23:30.772791Z","steps":["trace[706468361] 'agreement among raft nodes before linearized reading' (duration: 1.021667405s)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:30.772823Z","caller":"traceutil/trace.go:171","msg":"trace[1995479193] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4963; }","duration":"1.234836738s","start":"2026-05-19T22:23:29.537977Z","end":"2026-05-19T22:23:30.772814Z","steps":["trace[1995479193] 'agreement among raft nodes before linearized reading' (duration: 1.234749107s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:30.772841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:29.75104Z","time spent":"1.021789567s","remote":"127.0.0.1:44910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-05-19T22:23:30.772861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:29.537954Z","time spent":"1.234900149s","remote":"127.0.0.1:47690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T22:23:30.772712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233955775s","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-05-19T22:23:30.773008Z","caller":"traceutil/trace.go:171","msg":"trace[1738127682] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4963; }","duration":"1.234265069s","start":"2026-05-19T22:23:29.538732Z","end":"2026-05-19T22:23:30.772997Z","steps":["trace[1738127682] 'agreement among raft nodes before linearized reading' (duration: 1.233907273s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:30.773043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:29.538725Z","time spent":"1.23430802s","remote":"127.0.0.1:47690","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-05-19T22:23:30.773205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.236018067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T22:23:30.77332Z","caller":"traceutil/trace.go:171","msg":"trace[1511975695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4963; }","duration":"1.236160849s","start":"2026-05-19T22:23:29.537147Z","end":"2026-05-19T22:23:30.773308Z","steps":["trace[1511975695] 'agreement among raft nodes before linearized reading' (duration: 1.235959816s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:23:30.773454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:23:29.537136Z","time spent":"1.236304611s","remote":"127.0.0.1:47690","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-05-19T22:23:30.771354Z","caller":"traceutil/trace.go:171","msg":"trace[1410628093] linearizableReadLoop","detail":"{readStateIndex:5159; appliedIndex:5158; }","duration":"163.161175ms","start":"2026-05-19T22:23:30.608171Z","end":"2026-05-19T22:23:30.771332Z","steps":["trace[1410628093] 'read index received' (duration: 137.512409ms)","trace[1410628093] 'applied index is now lower than readState.Index' (duration: 25.647296ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:23:30.791036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.143865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-q99lk\" ","response":"range_response_count:1 size:9769"} {"level":"info","ts":"2026-05-19T22:23:30.791158Z","caller":"traceutil/trace.go:171","msg":"trace[1627519829] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-q99lk; range_end:; response_count:1; response_revision:4963; }","duration":"103.305677ms","start":"2026-05-19T22:23:30.687836Z","end":"2026-05-19T22:23:30.791142Z","steps":["trace[1627519829] 'agreement among raft nodes before linearized reading' (duration: 103.075163ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:30.993957Z","caller":"traceutil/trace.go:171","msg":"trace[1311399196] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"191.560175ms","start":"2026-05-19T22:23:30.802375Z","end":"2026-05-19T22:23:30.993935Z","steps":["trace[1311399196] 'process raft request' (duration: 191.495904ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:30.993993Z","caller":"traceutil/trace.go:171","msg":"trace[1493125175] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"202.012401ms","start":"2026-05-19T22:23:30.791959Z","end":"2026-05-19T22:23:30.993971Z","steps":["trace[1493125175] 'process raft request' (duration: 115.459259ms)","trace[1493125175] 'compare' (duration: 86.328728ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:23:31.37845Z","caller":"traceutil/trace.go:171","msg":"trace[1703050540] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"186.357228ms","start":"2026-05-19T22:23:31.192072Z","end":"2026-05-19T22:23:31.378429Z","steps":["trace[1703050540] 'process raft request' (duration: 150.44054ms)","trace[1703050540] 'compare' (duration: 35.625894ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:23:31.387888Z","caller":"traceutil/trace.go:171","msg":"trace[2125379108] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"189.117641ms","start":"2026-05-19T22:23:31.198735Z","end":"2026-05-19T22:23:31.387852Z","steps":["trace[2125379108] 'process raft request' (duration: 188.894708ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:51.563331Z","caller":"traceutil/trace.go:171","msg":"trace[579653169] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"181.593411ms","start":"2026-05-19T22:23:51.381715Z","end":"2026-05-19T22:23:51.563308Z","steps":["trace[579653169] 'process raft request' (duration: 181.435918ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:51.59384Z","caller":"traceutil/trace.go:171","msg":"trace[249303087] linearizableReadLoop","detail":"{readStateIndex:5322; appliedIndex:5320; }","duration":"104.773125ms","start":"2026-05-19T22:23:51.489028Z","end":"2026-05-19T22:23:51.593801Z","steps":["trace[249303087] 'read index received' (duration: 74.129686ms)","trace[249303087] 'applied index is now lower than readState.Index' (duration: 30.642249ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:23:51.593965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.918927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:23:51.594012Z","caller":"traceutil/trace.go:171","msg":"trace[869717510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5121; }","duration":"105.1165ms","start":"2026-05-19T22:23:51.488882Z","end":"2026-05-19T22:23:51.593998Z","steps":["trace[869717510] 'agreement among raft nodes before linearized reading' (duration: 105.028328ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:51.594171Z","caller":"traceutil/trace.go:171","msg":"trace[856334668] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"117.289996ms","start":"2026-05-19T22:23:51.476854Z","end":"2026-05-19T22:23:51.594144Z","steps":["trace[856334668] 'process raft request' (duration: 116.804048ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:59.750503Z","caller":"traceutil/trace.go:171","msg":"trace[39486483] transaction","detail":"{read_only:false; response_revision:5258; number_of_response:1; }","duration":"118.769927ms","start":"2026-05-19T22:23:59.631694Z","end":"2026-05-19T22:23:59.750464Z","steps":["trace[39486483] 'process raft request' (duration: 118.432312ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:24:02.901893Z","caller":"traceutil/trace.go:171","msg":"trace[802682697] transaction","detail":"{read_only:false; response_revision:5272; number_of_response:1; }","duration":"116.727089ms","start":"2026-05-19T22:24:02.78513Z","end":"2026-05-19T22:24:02.901857Z","steps":["trace[802682697] 'process raft request' (duration: 17.536094ms)","trace[802682697] 'compare' (duration: 98.955031ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:24:23.451319Z","caller":"traceutil/trace.go:171","msg":"trace[443160322] transaction","detail":"{read_only:false; response_revision:5410; number_of_response:1; }","duration":"128.746147ms","start":"2026-05-19T22:24:23.322544Z","end":"2026-05-19T22:24:23.45129Z","steps":["trace[443160322] 'process raft request' (duration: 128.570255ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:24:23.464349Z","caller":"traceutil/trace.go:171","msg":"trace[1430936851] linearizableReadLoop","detail":"{readStateIndex:5620; appliedIndex:5618; }","duration":"114.274614ms","start":"2026-05-19T22:24:23.350035Z","end":"2026-05-19T22:24:23.46431Z","steps":["trace[1430936851] 'read index received' (duration: 101.186742ms)","trace[1430936851] 'applied index is now lower than readState.Index' (duration: 13.087142ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:24:23.464496Z","caller":"traceutil/trace.go:171","msg":"trace[967870924] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"141.368252ms","start":"2026-05-19T22:24:23.323118Z","end":"2026-05-19T22:24:23.464486Z","steps":["trace[967870924] 'process raft request' (duration: 141.031857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:24:23.464651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.249785ms","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-05-19T22:24:23.464721Z","caller":"traceutil/trace.go:171","msg":"trace[480676492] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5411; }","duration":"112.340736ms","start":"2026-05-19T22:24:23.352357Z","end":"2026-05-19T22:24:23.464698Z","steps":["trace[480676492] 'agreement among raft nodes before linearized reading' (duration: 112.159283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:24:23.464983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.166454ms","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-05-19T22:24:23.465022Z","caller":"traceutil/trace.go:171","msg":"trace[187766547] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5411; }","duration":"112.213294ms","start":"2026-05-19T22:24:23.352799Z","end":"2026-05-19T22:24:23.465013Z","steps":["trace[187766547] 'agreement among raft nodes before linearized reading' (duration: 112.087683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:24:23.465133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.077576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T22:24:23.46518Z","caller":"traceutil/trace.go:171","msg":"trace[1456549053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5411; }","duration":"115.140898ms","start":"2026-05-19T22:24:23.35003Z","end":"2026-05-19T22:24:23.465171Z","steps":["trace[1456549053] 'agreement among raft nodes before linearized reading' (duration: 114.935725ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:24:46.960857Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2921} {"level":"info","ts":"2026-05-19T22:24:47.063499Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2921,"took":"101.030502ms","hash":978269259,"current-db-size-bytes":52764672,"current-db-size":"53 MB","current-db-size-in-use-bytes":29065216,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-19T22:24:47.063638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":978269259,"revision":2921,"compact-revision":-1} {"level":"info","ts":"2026-05-19T22:27:32.619681Z","caller":"traceutil/trace.go:171","msg":"trace[914267146] transaction","detail":"{read_only:false; response_revision:6735; number_of_response:1; }","duration":"100.139249ms","start":"2026-05-19T22:27:32.51952Z","end":"2026-05-19T22:27:32.61966Z","steps":["trace[914267146] 'process raft request' (duration: 92.813785ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:28:20.661742Z","caller":"traceutil/trace.go:171","msg":"trace[552633297] transaction","detail":"{read_only:false; response_revision:7006; number_of_response:1; }","duration":"103.641194ms","start":"2026-05-19T22:28:20.55807Z","end":"2026-05-19T22:28:20.661711Z","steps":["trace[552633297] 'process raft request' (duration: 103.464377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:28:21.412184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.392634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:28:21.412339Z","caller":"traceutil/trace.go:171","msg":"trace[741923889] transaction","detail":"{read_only:false; response_revision:7012; number_of_response:1; }","duration":"264.355021ms","start":"2026-05-19T22:28:21.147928Z","end":"2026-05-19T22:28:21.412283Z","steps":["trace[741923889] 'process raft request' (duration: 152.780359ms)","trace[741923889] 'compare' (duration: 111.265119ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:29:46.903592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.990356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-19T22:29:46.903675Z","caller":"traceutil/trace.go:171","msg":"trace[174503984] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:7439; }","duration":"124.149391ms","start":"2026-05-19T22:29:46.779506Z","end":"2026-05-19T22:29:46.903655Z","steps":["trace[174503984] 'range keys from in-memory index tree' (duration: 123.788169ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:29:47.010606Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5586} {"level":"info","ts":"2026-05-19T22:29:47.453001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5586,"took":"440.453534ms","hash":1070526194,"current-db-size-bytes":52764672,"current-db-size":"53 MB","current-db-size-in-use-bytes":18178048,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-19T22:29:47.453096Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1070526194,"revision":5586,"compact-revision":2921} {"level":"info","ts":"2026-05-19T22:29:50.138136Z","caller":"traceutil/trace.go:171","msg":"trace[1988263718] transaction","detail":"{read_only:false; response_revision:7460; number_of_response:1; }","duration":"101.237679ms","start":"2026-05-19T22:29:50.03685Z","end":"2026-05-19T22:29:50.138088Z","steps":["trace[1988263718] 'process raft request' (duration: 69.619258ms)","trace[1988263718] 'compare' (duration: 31.331251ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:29:58.000626Z","caller":"traceutil/trace.go:171","msg":"trace[1720028130] transaction","detail":"{read_only:false; response_revision:7496; number_of_response:1; }","duration":"115.90626ms","start":"2026-05-19T22:29:57.884687Z","end":"2026-05-19T22:29:58.000593Z","steps":["trace[1720028130] 'process raft request' (duration: 72.76676ms)","trace[1720028130] 'compare' (duration: 42.960284ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:30:06.050137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.56682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:namespace-controller\" ","response":"range_response_count:1 size:755"} {"level":"info","ts":"2026-05-19T22:30:06.050227Z","caller":"traceutil/trace.go:171","msg":"trace[27678094] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:namespace-controller; range_end:; response_count:1; response_revision:7535; }","duration":"100.682193ms","start":"2026-05-19T22:30:05.949521Z","end":"2026-05-19T22:30:06.050203Z","steps":["trace[27678094] 'range keys from in-memory index tree' (duration: 100.491177ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:30:06.188218Z","caller":"traceutil/trace.go:171","msg":"trace[455017043] linearizableReadLoop","detail":"{readStateIndex:7825; appliedIndex:7824; }","duration":"133.825906ms","start":"2026-05-19T22:30:06.054363Z","end":"2026-05-19T22:30:06.188189Z","steps":["trace[455017043] 'read index received' (duration: 133.694372ms)","trace[455017043] 'applied index is now lower than readState.Index' (duration: 130.434µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:30:06.188257Z","caller":"traceutil/trace.go:171","msg":"trace[364788291] transaction","detail":"{read_only:false; response_revision:7536; number_of_response:1; }","duration":"134.198208ms","start":"2026-05-19T22:30:06.05402Z","end":"2026-05-19T22:30:06.188218Z","steps":["trace[364788291] 'process raft request' (duration: 134.071844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:30:06.188465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.072164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:namespace-controller\" ","response":"range_response_count:1 size:755"} {"level":"info","ts":"2026-05-19T22:30:06.188565Z","caller":"traceutil/trace.go:171","msg":"trace[1099456811] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:namespace-controller; range_end:; response_count:1; response_revision:7536; }","duration":"134.210958ms","start":"2026-05-19T22:30:06.054336Z","end":"2026-05-19T22:30:06.188547Z","steps":["trace[1099456811] 'agreement among raft nodes before linearized reading' (duration: 133.95798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:30:06.383891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.317361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:volume-scheduler\" ","response":"range_response_count:1 size:725"} {"level":"info","ts":"2026-05-19T22:30:06.384055Z","caller":"traceutil/trace.go:171","msg":"trace[1055349533] range","detail":"{range_begin:/registry/clusterroles/system:volume-scheduler; range_end:; response_count:1; response_revision:7538; }","duration":"119.511838ms","start":"2026-05-19T22:30:06.264523Z","end":"2026-05-19T22:30:06.384035Z","steps":["trace[1055349533] 'range keys from in-memory index tree' (duration: 119.126544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:30:06.38408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.220971ms","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":"warn","ts":"2026-05-19T22:30:06.383946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.401757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:node-controller\" ","response":"range_response_count:1 size:735"} {"level":"info","ts":"2026-05-19T22:30:06.384162Z","caller":"traceutil/trace.go:171","msg":"trace[1617696973] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7538; }","duration":"113.334775ms","start":"2026-05-19T22:30:06.270808Z","end":"2026-05-19T22:30:06.384143Z","steps":["trace[1617696973] 'range keys from in-memory index tree' (duration: 113.084556ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:30:06.384219Z","caller":"traceutil/trace.go:171","msg":"trace[73448539] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:node-controller; range_end:; response_count:1; response_revision:7538; }","duration":"120.694376ms","start":"2026-05-19T22:30:06.263496Z","end":"2026-05-19T22:30:06.384191Z","steps":["trace[73448539] 'range keys from in-memory index tree' (duration: 120.329074ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:30:06.489675Z","caller":"traceutil/trace.go:171","msg":"trace[692509573] linearizableReadLoop","detail":"{readStateIndex:7828; appliedIndex:7827; }","duration":"100.551165ms","start":"2026-05-19T22:30:06.389103Z","end":"2026-05-19T22:30:06.489654Z","steps":["trace[692509573] 'read index received' (duration: 100.324798ms)","trace[692509573] 'applied index is now lower than readState.Index' (duration: 224.837µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:30:06.489807Z","caller":"traceutil/trace.go:171","msg":"trace[311522359] transaction","detail":"{read_only:false; response_revision:7539; number_of_response:1; }","duration":"100.866776ms","start":"2026-05-19T22:30:06.38891Z","end":"2026-05-19T22:30:06.489777Z","steps":["trace[311522359] 'process raft request' (duration: 100.563356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:30:06.489885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.766103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:node-controller\" ","response":"range_response_count:1 size:735"} {"level":"info","ts":"2026-05-19T22:30:06.48996Z","caller":"traceutil/trace.go:171","msg":"trace[1089957002] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:node-controller; range_end:; response_count:1; response_revision:7539; }","duration":"100.887196ms","start":"2026-05-19T22:30:06.389054Z","end":"2026-05-19T22:30:06.489941Z","steps":["trace[1089957002] 'agreement among raft nodes before linearized reading' (duration: 100.737292ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:30:06.896281Z","caller":"traceutil/trace.go:171","msg":"trace[1323586402] linearizableReadLoop","detail":"{readStateIndex:7829; appliedIndex:7828; }","duration":"114.185762ms","start":"2026-05-19T22:30:06.782055Z","end":"2026-05-19T22:30:06.89624Z","steps":["trace[1323586402] 'read index received' (duration: 113.793419ms)","trace[1323586402] 'applied index is now lower than readState.Index' (duration: 386.203µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:30:06.896751Z","caller":"traceutil/trace.go:171","msg":"trace[863173649] transaction","detail":"{read_only:false; response_revision:7540; number_of_response:1; }","duration":"115.96359ms","start":"2026-05-19T22:30:06.780745Z","end":"2026-05-19T22:30:06.896709Z","steps":["trace[863173649] 'process raft request' (duration: 115.233057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:30:06.896811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.72065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pv-protection-controller\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-05-19T22:30:06.896861Z","caller":"traceutil/trace.go:171","msg":"trace[474522978] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pv-protection-controller; range_end:; response_count:1; response_revision:7540; }","duration":"114.858794ms","start":"2026-05-19T22:30:06.781987Z","end":"2026-05-19T22:30:06.896846Z","steps":["trace[474522978] 'agreement among raft nodes before linearized reading' (duration: 114.718619ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:30:07.034192Z","caller":"traceutil/trace.go:171","msg":"trace[1683551612] transaction","detail":"{read_only:false; response_revision:7541; number_of_response:1; }","duration":"120.385264ms","start":"2026-05-19T22:30:06.913765Z","end":"2026-05-19T22:30:07.03415Z","steps":["trace[1683551612] 'process raft request' (duration: 67.007426ms)","trace[1683551612] 'compare' (duration: 52.908892ms)"],"step_count":2}