{"level":"warn","ts":"2026-05-25T22:09:02.285979Z","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-25T22:09:02.286068Z","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-25T22:09:02.286134Z","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-25T22:09:02.286141Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-05-25T22:09:02.286159Z","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-25T22:09:02.286802Z","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-25T22:09:02.286875Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.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-25T22:09:02.28984Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.737586ms"} {"level":"info","ts":"2026-05-25T22:09:02.294794Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-05-25T22:09:02.294882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-05-25T22:09:02.294904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-05-25T22:09:02.29492Z","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-25T22:09:02.294933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-05-25T22:09:02.294975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-05-25T22:09:02.304185Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-25T22:09:02.306174Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-25T22:09:02.307442Z","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-25T22:09:02.309587Z","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-25T22:09:02.309828Z","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-25T22:09:02.309959Z","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-25T22:09:02.310145Z","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-25T22:09:02.310187Z","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-25T22:09:02.312652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-05-25T22:09:02.312792Z","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-25T22:09:02.315612Z","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-25T22:09:02.31575Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-05-25T22:09:02.315819Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-05-25T22:09:02.31605Z","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-25T22:09:02.316104Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-25T22:09:03.195917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-05-25T22:09:03.195982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-25T22:09:03.196015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-05-25T22:09:03.196031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-05-25T22:09:03.196043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-05-25T22:09:03.196057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-05-25T22:09:03.196104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-05-25T22:09:03.196847Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T22:09:03.19818Z","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-25T22:09:03.198272Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T22:09:03.198294Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T22:09:03.198322Z","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-25T22:09:03.198452Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T22:09:03.19853Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T22:09:03.200375Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-05-25T22:09:03.200509Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-25T22:09:03.200527Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-25T22:09:03.201787Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-25T22:09:07.260454Z","caller":"traceutil/trace.go:171","msg":"trace[1226062314] linearizableReadLoop","detail":"{readStateIndex:210; appliedIndex:209; }","duration":"190.018242ms","start":"2026-05-25T22:09:07.070398Z","end":"2026-05-25T22:09:07.260417Z","steps":["trace[1226062314] 'read index received' (duration: 189.734324ms)","trace[1226062314] 'applied index is now lower than readState.Index' (duration: 282.528µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:09:07.260561Z","caller":"traceutil/trace.go:171","msg":"trace[778590778] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"194.919629ms","start":"2026-05-25T22:09:07.065604Z","end":"2026-05-25T22:09:07.260524Z","steps":["trace[778590778] 'process raft request' (duration: 194.61948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:07.260762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.31022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3496"} {"level":"info","ts":"2026-05-25T22:09:07.260838Z","caller":"traceutil/trace.go:171","msg":"trace[1596960204] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:205; }","duration":"190.485795ms","start":"2026-05-25T22:09:07.070336Z","end":"2026-05-25T22:09:07.260822Z","steps":["trace[1596960204] 'agreement among raft nodes before linearized reading' (duration: 190.262949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:13.176303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.694925ms","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-25T22:09:13.176432Z","caller":"traceutil/trace.go:171","msg":"trace[149971795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:344; }","duration":"174.902221ms","start":"2026-05-25T22:09:13.001502Z","end":"2026-05-25T22:09:13.176404Z","steps":["trace[149971795] 'range keys from in-memory index tree' (duration: 174.514629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:13.652326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.173622ms","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-25T22:09:13.652457Z","caller":"traceutil/trace.go:171","msg":"trace[581250631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:345; }","duration":"285.372377ms","start":"2026-05-25T22:09:13.367057Z","end":"2026-05-25T22:09:13.65243Z","steps":["trace[581250631] 'range keys from in-memory index tree' (duration: 285.017817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:13.6525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.642272ms","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-25T22:09:13.652673Z","caller":"traceutil/trace.go:171","msg":"trace[2096689090] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:345; }","duration":"300.886389ms","start":"2026-05-25T22:09:13.351749Z","end":"2026-05-25T22:09:13.652636Z","steps":["trace[2096689090] 'range keys from in-memory index tree' (duration: 300.433046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:13.652761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:09:13.351721Z","time spent":"301.020873ms","remote":"127.0.0.1:40076","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-25T22:09:43.523718Z","caller":"traceutil/trace.go:171","msg":"trace[576874267] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"136.138611ms","start":"2026-05-25T22:09:43.387553Z","end":"2026-05-25T22:09:43.523692Z","steps":["trace[576874267] 'read index received' (duration: 136.022338ms)","trace[576874267] 'applied index is now lower than readState.Index' (duration: 114.973µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:09:43.523863Z","caller":"traceutil/trace.go:171","msg":"trace[1512125877] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"174.928442ms","start":"2026-05-25T22:09:43.348885Z","end":"2026-05-25T22:09:43.523813Z","steps":["trace[1512125877] 'process raft request' (duration: 174.645394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:43.524043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.45395ms","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-25T22:09:43.524092Z","caller":"traceutil/trace.go:171","msg":"trace[781198438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:566; }","duration":"136.564443ms","start":"2026-05-25T22:09:43.387516Z","end":"2026-05-25T22:09:43.52408Z","steps":["trace[781198438] 'agreement among raft nodes before linearized reading' (duration: 136.308376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:47.251197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.28389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T22:09:47.251355Z","caller":"traceutil/trace.go:171","msg":"trace[541008239] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"153.536176ms","start":"2026-05-25T22:09:47.097796Z","end":"2026-05-25T22:09:47.251332Z","steps":["trace[541008239] 'process raft request' (duration: 14.477034ms)","trace[541008239] 'compare' (duration: 138.108235ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:09:47.767017Z","caller":"traceutil/trace.go:171","msg":"trace[2060525798] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"271.044761ms","start":"2026-05-25T22:09:47.495943Z","end":"2026-05-25T22:09:47.766987Z","steps":["trace[2060525798] 'read index received' (duration: 270.255129ms)","trace[2060525798] 'applied index is now lower than readState.Index' (duration: 788.822µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:09:47.76717Z","caller":"traceutil/trace.go:171","msg":"trace[1386428510] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"358.76237ms","start":"2026-05-25T22:09:47.408358Z","end":"2026-05-25T22:09:47.767121Z","steps":["trace[1386428510] 'process raft request' (duration: 358.101681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:47.767381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:09:47.408321Z","time spent":"358.942115ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:09:47.767389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.429761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6tvzn\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-25T22:09:47.76754Z","caller":"traceutil/trace.go:171","msg":"trace[566786744] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6tvzn; range_end:; response_count:1; response_revision:595; }","duration":"271.616816ms","start":"2026-05-25T22:09:47.49589Z","end":"2026-05-25T22:09:47.767507Z","steps":["trace[566786744] 'agreement among raft nodes before linearized reading' (duration: 271.312758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:09:47.767723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.479298ms","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-25T22:09:47.767865Z","caller":"traceutil/trace.go:171","msg":"trace[320116531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"207.677114ms","start":"2026-05-25T22:09:47.560158Z","end":"2026-05-25T22:09:47.767835Z","steps":["trace[320116531] 'agreement among raft nodes before linearized reading' (duration: 207.372575ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:11:53.980558Z","caller":"traceutil/trace.go:171","msg":"trace[2090384982] linearizableReadLoop","detail":"{readStateIndex:2148; appliedIndex:2147; }","duration":"210.530291ms","start":"2026-05-25T22:11:53.769996Z","end":"2026-05-25T22:11:53.980527Z","steps":["trace[2090384982] 'read index received' (duration: 210.362757ms)","trace[2090384982] 'applied index is now lower than readState.Index' (duration: 165.744µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:11:53.980726Z","caller":"traceutil/trace.go:171","msg":"trace[467465036] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"228.70972ms","start":"2026-05-25T22:11:53.751994Z","end":"2026-05-25T22:11:53.980704Z","steps":["trace[467465036] 'process raft request' (duration: 228.33765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:11:53.980877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.870671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-25T22:11:53.981077Z","caller":"traceutil/trace.go:171","msg":"trace[985443172] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2072; }","duration":"211.116308ms","start":"2026-05-25T22:11:53.769937Z","end":"2026-05-25T22:11:53.981053Z","steps":["trace[985443172] 'agreement among raft nodes before linearized reading' (duration: 210.787779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:00.847438Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057943045,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:01.347766Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057943045,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:01.848982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057943045,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:02.349682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057943045,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:02.554741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999688642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-25T22:12:02.554849Z","caller":"traceutil/trace.go:171","msg":"trace[1641657249] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999812496s","start":"2026-05-25T22:12:00.555017Z","end":"2026-05-25T22:12:02.554829Z","steps":["trace[1641657249] 'agreement among raft nodes before linearized reading' (duration: 1.999685322s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:02.554904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:00.554946Z","time spent":"1.999947359s","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/25 22:12:02 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-25T22:12:02.571488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000006971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-25T22:12:02.571597Z","caller":"traceutil/trace.go:171","msg":"trace[232315290] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000169236s","start":"2026-05-25T22:12:00.57141Z","end":"2026-05-25T22:12:02.57158Z","steps":["trace[232315290] 'agreement among raft nodes before linearized reading' (duration: 2.000055923s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:02.571654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:00.571348Z","time spent":"2.00029104s","remote":"127.0.0.1:39824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-25T22:12:02.850285Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057943045,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:03.350662Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057943045,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:03.576169Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.565179881s","expected-duration":"1s"} {"level":"info","ts":"2026-05-25T22:12:03.576796Z","caller":"traceutil/trace.go:171","msg":"trace[371813022] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"3.565946303s","start":"2026-05-25T22:12:00.010824Z","end":"2026-05-25T22:12:03.57677Z","steps":["trace[371813022] 'process raft request' (duration: 3.565734797s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:03.577032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:00.010785Z","time spent":"3.566088667s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:12:03.683823Z","caller":"traceutil/trace.go:171","msg":"trace[1519485477] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"3.670093543s","start":"2026-05-25T22:12:00.013707Z","end":"2026-05-25T22:12:03.683801Z","steps":["trace[1519485477] 'process raft request' (duration: 3.652284207s)","trace[1519485477] 'compare' (duration: 17.61548ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:12:03.683976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:00.013678Z","time spent":"3.670215455s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:12:03.684192Z","caller":"traceutil/trace.go:171","msg":"trace[918605418] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"3.512897365s","start":"2026-05-25T22:12:00.171269Z","end":"2026-05-25T22:12:03.684166Z","steps":["trace[918605418] 'process raft request' (duration: 3.512492383s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:03.684384Z","caller":"traceutil/trace.go:171","msg":"trace[1737030840] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"2.961806525s","start":"2026-05-25T22:12:00.722567Z","end":"2026-05-25T22:12:03.684374Z","steps":["trace[1737030840] 'process raft request' (duration: 2.961518777s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:03.684402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:00.171242Z","time spent":"3.513026499s","remote":"127.0.0.1:40076","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-25T22:12:03.684462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:00.722542Z","time spent":"2.961888277s","remote":"127.0.0.1:40076","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-25T22:12:03.684562Z","caller":"traceutil/trace.go:171","msg":"trace[695854095] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"2.946231801s","start":"2026-05-25T22:12:00.738251Z","end":"2026-05-25T22:12:03.684483Z","steps":["trace[695854095] 'process raft request' (duration: 2.946029655s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:03.684628Z","caller":"traceutil/trace.go:171","msg":"trace[272789569] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"2.493984304s","start":"2026-05-25T22:12:01.190605Z","end":"2026-05-25T22:12:03.684589Z","steps":["trace[272789569] 'process raft request' (duration: 2.493730036s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:03.684733Z","caller":"traceutil/trace.go:171","msg":"trace[1972833360] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"2.200769153s","start":"2026-05-25T22:12:01.483845Z","end":"2026-05-25T22:12:03.684614Z","steps":["trace[1972833360] 'process raft request' (duration: 2.200525476s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:03.684805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:00.738231Z","time spent":"2.946450227s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:12:03.684911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:01.483814Z","time spent":"2.201029031s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:12:03.685029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:01.190526Z","time spent":"2.494267422s","remote":"127.0.0.1:40076","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-25T22:12:03.810208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.75268ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-25T22:12:03.811534Z","caller":"traceutil/trace.go:171","msg":"trace[1912213725] linearizableReadLoop","detail":"{readStateIndex:2188; appliedIndex:2180; }","duration":"3.464293732s","start":"2026-05-25T22:12:00.347096Z","end":"2026-05-25T22:12:03.811389Z","steps":["trace[1912213725] 'read index received' (duration: 3.229416834s)","trace[1912213725] 'applied index is now lower than readState.Index' (duration: 234.374524ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:12:03.811603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.464499108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-25T22:12:03.811741Z","caller":"traceutil/trace.go:171","msg":"trace[1204504786] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2110; }","duration":"3.464667912s","start":"2026-05-25T22:12:00.347037Z","end":"2026-05-25T22:12:03.811705Z","steps":["trace[1204504786] 'agreement among raft nodes before linearized reading' (duration: 3.464397515s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:03.811927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:00.347013Z","time spent":"3.464776985s","remote":"127.0.0.1:46954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-25T22:12:03.812244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.331304311s","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-25T22:12:03.81233Z","caller":"traceutil/trace.go:171","msg":"trace[1435021541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2110; }","duration":"3.331449375s","start":"2026-05-25T22:12:00.480856Z","end":"2026-05-25T22:12:03.812305Z","steps":["trace[1435021541] 'agreement among raft nodes before linearized reading' (duration: 3.331070434s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:03.812321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.203236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T22:12:03.812318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.122146878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T22:12:03.812385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:00.480834Z","time spent":"3.331529757s","remote":"127.0.0.1:40076","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-25T22:12:03.812438Z","caller":"traceutil/trace.go:171","msg":"trace[1019978092] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2110; }","duration":"651.399602ms","start":"2026-05-25T22:12:03.161005Z","end":"2026-05-25T22:12:03.812405Z","steps":["trace[1019978092] 'agreement among raft nodes before linearized reading' (duration: 650.968411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:03.812478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:03.160984Z","time spent":"651.484044ms","remote":"127.0.0.1:40102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:12:03.812492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234171095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:12:03.812668Z","caller":"traceutil/trace.go:171","msg":"trace[1775856002] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:2110; }","duration":"3.122319813s","start":"2026-05-25T22:12:00.690078Z","end":"2026-05-25T22:12:03.812398Z","steps":["trace[1775856002] 'agreement among raft nodes before linearized reading' (duration: 3.122096737s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:03.812734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:00.690056Z","time spent":"3.122661612s","remote":"127.0.0.1:35410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:12:03.812725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.965650447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b2ee906f72926f\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-05-25T22:12:03.812773Z","caller":"traceutil/trace.go:171","msg":"trace[614514222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2110; }","duration":"1.23435691s","start":"2026-05-25T22:12:02.57826Z","end":"2026-05-25T22:12:03.812617Z","steps":["trace[614514222] 'agreement among raft nodes before linearized reading' (duration: 1.233742363s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:03.813096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:02.578244Z","time spent":"1.234639609s","remote":"127.0.0.1:39838","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-25T22:12:03.813108Z","caller":"traceutil/trace.go:171","msg":"trace[40638668] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b2ee906f72926f; range_end:; response_count:1; response_revision:2110; }","duration":"1.965915365s","start":"2026-05-25T22:12:01.846961Z","end":"2026-05-25T22:12:03.812876Z","steps":["trace[40638668] 'agreement among raft nodes before linearized reading' (duration: 1.965470162s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:03.813154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.252720283s","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-25T22:12:03.813318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.521036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-25T22:12:03.813336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:01.846938Z","time spent":"1.966237324s","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b2ee906f72926f\" "} {"level":"info","ts":"2026-05-25T22:12:03.813425Z","caller":"traceutil/trace.go:171","msg":"trace[1139950369] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2110; }","duration":"263.997148ms","start":"2026-05-25T22:12:03.549376Z","end":"2026-05-25T22:12:03.813373Z","steps":["trace[1139950369] 'agreement among raft nodes before linearized reading' (duration: 263.425092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:03.81351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.25294915s","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-25T22:12:03.813664Z","caller":"traceutil/trace.go:171","msg":"trace[355201448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2110; }","duration":"3.253161265s","start":"2026-05-25T22:12:00.560446Z","end":"2026-05-25T22:12:03.813607Z","steps":["trace[355201448] 'agreement among raft nodes before linearized reading' (duration: 3.252173277s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:03.813729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:00.560429Z","time spent":"3.253283719s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-25T22:12:03.81427Z","caller":"traceutil/trace.go:171","msg":"trace[2131585669] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2110; }","duration":"3.252791315s","start":"2026-05-25T22:12:00.560384Z","end":"2026-05-25T22:12:03.813176Z","steps":["trace[2131585669] 'agreement among raft nodes before linearized reading' (duration: 3.252654311s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:03.814328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:00.560361Z","time spent":"3.253955578s","remote":"127.0.0.1:40076","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-25T22:12:03.81428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.749786227s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-05-25T22:12:03.815621Z","caller":"traceutil/trace.go:171","msg":"trace[137249238] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2110; }","duration":"1.751296919s","start":"2026-05-25T22:12:02.064302Z","end":"2026-05-25T22:12:03.815599Z","steps":["trace[137249238] 'agreement among raft nodes before linearized reading' (duration: 1.747627127s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:03.815693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:02.064281Z","time spent":"1.751399633s","remote":"127.0.0.1:39972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12409,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-25T22:12:04.086316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.313297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T22:12:04.086608Z","caller":"traceutil/trace.go:171","msg":"trace[353259755] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"268.152344ms","start":"2026-05-25T22:12:03.818421Z","end":"2026-05-25T22:12:04.086573Z","steps":["trace[353259755] 'process raft request' (duration: 109.503658ms)","trace[353259755] 'compare' (duration: 158.169593ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:12:04.086656Z","caller":"traceutil/trace.go:171","msg":"trace[904993096] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"175.257328ms","start":"2026-05-25T22:12:03.911378Z","end":"2026-05-25T22:12:04.086635Z","steps":["trace[904993096] 'process raft request' (duration: 175.197616ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:04.086839Z","caller":"traceutil/trace.go:171","msg":"trace[1542020491] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"268.086752ms","start":"2026-05-25T22:12:03.818734Z","end":"2026-05-25T22:12:04.086821Z","steps":["trace[1542020491] 'process raft request' (duration: 267.747693ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:04.086901Z","caller":"traceutil/trace.go:171","msg":"trace[1143980074] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2188; }","duration":"265.767958ms","start":"2026-05-25T22:12:03.821097Z","end":"2026-05-25T22:12:04.086865Z","steps":["trace[1143980074] 'read index received' (duration: 86.058186ms)","trace[1143980074] 'applied index is now lower than readState.Index' (duration: 179.703502ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:12:04.086916Z","caller":"traceutil/trace.go:171","msg":"trace[807941816] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"267.610209ms","start":"2026-05-25T22:12:03.819272Z","end":"2026-05-25T22:12:04.086882Z","steps":["trace[807941816] 'process raft request' (duration: 267.265209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:04.087006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.894032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:12:04.087033Z","caller":"traceutil/trace.go:171","msg":"trace[2074625555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2115; }","duration":"265.956193ms","start":"2026-05-25T22:12:03.821069Z","end":"2026-05-25T22:12:04.087025Z","steps":["trace[2074625555] 'agreement among raft nodes before linearized reading' (duration: 265.896041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:04.087127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.426035ms","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-25T22:12:04.087175Z","caller":"traceutil/trace.go:171","msg":"trace[837724791] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2115; }","duration":"262.496917ms","start":"2026-05-25T22:12:03.824663Z","end":"2026-05-25T22:12:04.08716Z","steps":["trace[837724791] 'agreement among raft nodes before linearized reading' (duration: 262.379143ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:04.295258Z","caller":"traceutil/trace.go:171","msg":"trace[654032030] linearizableReadLoop","detail":"{readStateIndex:2194; appliedIndex:2193; }","duration":"195.78727ms","start":"2026-05-25T22:12:04.099437Z","end":"2026-05-25T22:12:04.295224Z","steps":["trace[654032030] 'read index received' (duration: 146.174509ms)","trace[654032030] 'applied index is now lower than readState.Index' (duration: 49.611681ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:12:04.295382Z","caller":"traceutil/trace.go:171","msg":"trace[1260582431] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"204.488722ms","start":"2026-05-25T22:12:04.090838Z","end":"2026-05-25T22:12:04.295327Z","steps":["trace[1260582431] 'process raft request' (duration: 154.84457ms)","trace[1260582431] 'compare' (duration: 49.352454ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:12:04.295614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.15097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-25T22:12:04.295686Z","caller":"traceutil/trace.go:171","msg":"trace[1323284139] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2116; }","duration":"196.271654ms","start":"2026-05-25T22:12:04.099393Z","end":"2026-05-25T22:12:04.295665Z","steps":["trace[1323284139] 'agreement among raft nodes before linearized reading' (duration: 195.970735ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:08.285801Z","caller":"traceutil/trace.go:171","msg":"trace[1609343398] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"179.141596ms","start":"2026-05-25T22:12:08.106623Z","end":"2026-05-25T22:12:08.285765Z","steps":["trace[1609343398] 'process raft request' (duration: 120.547786ms)","trace[1609343398] 'compare' (duration: 58.389055ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:12:08.286121Z","caller":"traceutil/trace.go:171","msg":"trace[1805731320] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"177.872601ms","start":"2026-05-25T22:12:08.108202Z","end":"2026-05-25T22:12:08.286075Z","steps":["trace[1805731320] 'process raft request' (duration: 177.504251ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:14.32945Z","caller":"traceutil/trace.go:171","msg":"trace[1253898615] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"105.939629ms","start":"2026-05-25T22:12:14.223487Z","end":"2026-05-25T22:12:14.329426Z","steps":["trace[1253898615] 'process raft request' (duration: 105.749164ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:14.537976Z","caller":"traceutil/trace.go:171","msg":"trace[394978468] linearizableReadLoop","detail":"{readStateIndex:2252; appliedIndex:2250; }","duration":"198.016951ms","start":"2026-05-25T22:12:14.339932Z","end":"2026-05-25T22:12:14.537949Z","steps":["trace[394978468] 'read index received' (duration: 154.089168ms)","trace[394978468] 'applied index is now lower than readState.Index' (duration: 43.926583ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:12:14.53813Z","caller":"traceutil/trace.go:171","msg":"trace[977059230] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"204.43445ms","start":"2026-05-25T22:12:14.333656Z","end":"2026-05-25T22:12:14.53809Z","steps":["trace[977059230] 'process raft request' (duration: 160.291451ms)","trace[977059230] 'compare' (duration: 43.727298ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:12:14.538131Z","caller":"traceutil/trace.go:171","msg":"trace[362168865] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"204.43931ms","start":"2026-05-25T22:12:14.333677Z","end":"2026-05-25T22:12:14.538116Z","steps":["trace[362168865] 'process raft request' (duration: 204.211163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:14.53836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.369822ms","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-25T22:12:14.538496Z","caller":"traceutil/trace.go:171","msg":"trace[1062098367] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2171; }","duration":"198.599068ms","start":"2026-05-25T22:12:14.339879Z","end":"2026-05-25T22:12:14.538478Z","steps":["trace[1062098367] 'agreement among raft nodes before linearized reading' (duration: 198.30556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:14.786245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.421031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:12:14.786329Z","caller":"traceutil/trace.go:171","msg":"trace[2110680277] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2171; }","duration":"102.570355ms","start":"2026-05-25T22:12:14.68374Z","end":"2026-05-25T22:12:14.78631Z","steps":["trace[2110680277] 'count revisions from in-memory index tree' (duration: 102.248746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:30.967453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.782114ms","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-25T22:12:30.967544Z","caller":"traceutil/trace.go:171","msg":"trace[1095853737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2267; }","duration":"350.96045ms","start":"2026-05-25T22:12:30.616565Z","end":"2026-05-25T22:12:30.967526Z","steps":["trace[1095853737] 'range keys from in-memory index tree' (duration: 350.570809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:30.96758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:30.616534Z","time spent":"351.036352ms","remote":"127.0.0.1:40076","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-25T22:12:30.967694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.739312ms","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-25T22:12:30.967787Z","caller":"traceutil/trace.go:171","msg":"trace[612000447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2267; }","duration":"355.896987ms","start":"2026-05-25T22:12:30.611869Z","end":"2026-05-25T22:12:30.967766Z","steps":["trace[612000447] 'range keys from in-memory index tree' (duration: 355.459114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:30.96784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:30.611843Z","time spent":"355.984759ms","remote":"127.0.0.1:40076","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-25T22:12:31.109392Z","caller":"traceutil/trace.go:171","msg":"trace[1248148401] transaction","detail":"{read_only:false; response_revision:2268; number_of_response:1; }","duration":"136.418217ms","start":"2026-05-25T22:12:30.972945Z","end":"2026-05-25T22:12:31.109363Z","steps":["trace[1248148401] 'process raft request' (duration: 136.218941ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:31.271174Z","caller":"traceutil/trace.go:171","msg":"trace[889674835] linearizableReadLoop","detail":"{readStateIndex:2353; appliedIndex:2351; }","duration":"254.911275ms","start":"2026-05-25T22:12:31.016228Z","end":"2026-05-25T22:12:31.271139Z","steps":["trace[889674835] 'read index received' (duration: 92.963487ms)","trace[889674835] 'applied index is now lower than readState.Index' (duration: 161.945838ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:12:31.271335Z","caller":"traceutil/trace.go:171","msg":"trace[2052530874] transaction","detail":"{read_only:false; response_revision:2269; number_of_response:1; }","duration":"298.267422ms","start":"2026-05-25T22:12:30.973002Z","end":"2026-05-25T22:12:31.27127Z","steps":["trace[2052530874] 'process raft request' (duration: 278.905023ms)","trace[2052530874] 'compare' (duration: 19.088211ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:12:31.271498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.240694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5428"} {"level":"info","ts":"2026-05-25T22:12:31.271544Z","caller":"traceutil/trace.go:171","msg":"trace[1173227415] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2269; }","duration":"255.362347ms","start":"2026-05-25T22:12:31.016169Z","end":"2026-05-25T22:12:31.271532Z","steps":["trace[1173227415] 'agreement among raft nodes before linearized reading' (duration: 255.134491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:32.074496Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057943447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:32.574994Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057943447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:33.076128Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057943447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:33.574151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000333353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-25T22:12:33.574251Z","caller":"traceutil/trace.go:171","msg":"trace[1648355120] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000502057s","start":"2026-05-25T22:12:31.573725Z","end":"2026-05-25T22:12:33.574227Z","steps":["trace[1648355120] 'agreement among raft nodes before linearized reading' (duration: 2.000379704s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:33.574329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:31.573693Z","time spent":"2.00060412s","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/25 22:12:33 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-25T22:12:33.576585Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057943447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:34.077697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057943447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:34.578494Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057943447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:35.079433Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057943447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:12:35.27742Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.728651092s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-25T22:12:35.331532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.928085ms","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-25T22:12:35.331673Z","caller":"traceutil/trace.go:171","msg":"trace[732140375] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2269; }","duration":"163.08365ms","start":"2026-05-25T22:12:35.16857Z","end":"2026-05-25T22:12:35.331653Z","steps":["trace[732140375] 'range keys from in-memory index tree' (duration: 162.913865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.427967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.262823ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-25T22:12:35.428385Z","caller":"traceutil/trace.go:171","msg":"trace[416465742] transaction","detail":"{read_only:false; response_revision:2271; number_of_response:1; }","duration":"3.649318654s","start":"2026-05-25T22:12:31.779038Z","end":"2026-05-25T22:12:35.428357Z","steps":["trace[416465742] 'process raft request' (duration: 3.649160669s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:35.428375Z","caller":"traceutil/trace.go:171","msg":"trace[281768557] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"3.650108906s","start":"2026-05-25T22:12:31.778219Z","end":"2026-05-25T22:12:35.428328Z","steps":["trace[281768557] 'process raft request' (duration: 3.649858869s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.428514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:31.778998Z","time spent":"3.649444137s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:12:35.428532Z","caller":"traceutil/trace.go:171","msg":"trace[576218728] transaction","detail":"{read_only:false; response_revision:2272; number_of_response:1; }","duration":"3.646777863s","start":"2026-05-25T22:12:31.781733Z","end":"2026-05-25T22:12:35.428511Z","steps":["trace[576218728] 'process raft request' (duration: 3.646507425s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.428566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:31.778191Z","time spent":"3.650268999s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:12:35.428623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:31.781715Z","time spent":"3.646858655s","remote":"127.0.0.1:40076","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-25T22:12:35.428789Z","caller":"traceutil/trace.go:171","msg":"trace[1679680827] linearizableReadLoop","detail":"{readStateIndex:2360; appliedIndex:2353; }","duration":"3.854980298s","start":"2026-05-25T22:12:31.573796Z","end":"2026-05-25T22:12:35.428776Z","steps":["trace[1679680827] 'read index received' (duration: 3.703924473s)","trace[1679680827] 'applied index is now lower than readState.Index' (duration: 151.055035ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:12:35.428834Z","caller":"traceutil/trace.go:171","msg":"trace[1647280121] transaction","detail":"{read_only:false; response_revision:2274; number_of_response:1; }","duration":"1.17258868s","start":"2026-05-25T22:12:34.256215Z","end":"2026-05-25T22:12:35.428804Z","steps":["trace[1647280121] 'process raft request' (duration: 1.172492907s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:35.428852Z","caller":"traceutil/trace.go:171","msg":"trace[457995204] transaction","detail":"{read_only:false; response_revision:2275; number_of_response:1; }","duration":"578.621187ms","start":"2026-05-25T22:12:34.850197Z","end":"2026-05-25T22:12:35.428818Z","steps":["trace[457995204] 'process raft request' (duration: 578.547615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.428971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:34.850163Z","time spent":"578.73917ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:12:35.428954Z","caller":"traceutil/trace.go:171","msg":"trace[140612847] transaction","detail":"{read_only:false; response_revision:2273; number_of_response:1; }","duration":"3.639927582s","start":"2026-05-25T22:12:31.788982Z","end":"2026-05-25T22:12:35.42891Z","steps":["trace[140612847] 'process raft request' (duration: 3.639302365s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.429026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:34.256183Z","time spent":"1.172711714s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:12:35.429094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.236633209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T22:12:35.429126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.441010145s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T22:12:35.429159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.828433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T22:12:35.429195Z","caller":"traceutil/trace.go:171","msg":"trace[1103337677] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2275; }","duration":"3.441102008s","start":"2026-05-25T22:12:31.988078Z","end":"2026-05-25T22:12:35.42918Z","steps":["trace[1103337677] 'agreement among raft nodes before linearized reading' (duration: 3.440994405s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:35.429205Z","caller":"traceutil/trace.go:171","msg":"trace[2102712938] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2275; }","duration":"832.924185ms","start":"2026-05-25T22:12:34.596269Z","end":"2026-05-25T22:12:35.429193Z","steps":["trace[2102712938] 'agreement among raft nodes before linearized reading' (duration: 832.819392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.429198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.804835039s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-25T22:12:35.429198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:31.788949Z","time spent":"3.640102067s","remote":"127.0.0.1:40076","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-25T22:12:35.429218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.854553633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T22:12:35.429238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:34.596241Z","time spent":"832.990527ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-05-25T22:12:35.429243Z","caller":"traceutil/trace.go:171","msg":"trace[1798253374] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2275; }","duration":"1.804916721s","start":"2026-05-25T22:12:33.62432Z","end":"2026-05-25T22:12:35.429237Z","steps":["trace[1798253374] 'agreement among raft nodes before linearized reading' (duration: 1.804853859s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.429239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:31.988065Z","time spent":"3.441161439s","remote":"127.0.0.1:35284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-05-25T22:12:35.429271Z","caller":"traceutil/trace.go:171","msg":"trace[1467815924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2275; }","duration":"1.854637106s","start":"2026-05-25T22:12:33.574617Z","end":"2026-05-25T22:12:35.429254Z","steps":["trace[1467815924] 'agreement among raft nodes before linearized reading' (duration: 1.854518002s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.429269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:33.624298Z","time spent":"1.804964283s","remote":"127.0.0.1:59638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:12:35.4293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:33.574573Z","time spent":"1.854720468s","remote":"127.0.0.1:39824","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-25T22:12:35.429378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.011574611s","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-25T22:12:35.429384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.459565852s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:12:35.429405Z","caller":"traceutil/trace.go:171","msg":"trace[1968912905] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2275; }","duration":"3.011655464s","start":"2026-05-25T22:12:32.417743Z","end":"2026-05-25T22:12:35.429399Z","steps":["trace[1968912905] 'agreement among raft nodes before linearized reading' (duration: 3.011557241s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.429361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.913318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T22:12:35.429427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:32.417684Z","time spent":"3.011739065s","remote":"127.0.0.1:40076","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-25T22:12:35.429437Z","caller":"traceutil/trace.go:171","msg":"trace[12273032] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2275; }","duration":"3.459660264s","start":"2026-05-25T22:12:31.969761Z","end":"2026-05-25T22:12:35.429421Z","steps":["trace[12273032] 'agreement among raft nodes before linearized reading' (duration: 3.459579912s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.42913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.246024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-25T22:12:35.429523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.314203398s","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-25T22:12:35.429572Z","caller":"traceutil/trace.go:171","msg":"trace[1223420632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2275; }","duration":"2.3142702s","start":"2026-05-25T22:12:33.115283Z","end":"2026-05-25T22:12:35.429553Z","steps":["trace[1223420632] 'agreement among raft nodes before linearized reading' (duration: 2.314083765s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.429565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.149327846s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5428"} {"level":"warn","ts":"2026-05-25T22:12:35.429627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:33.115261Z","time spent":"2.314351083s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-25T22:12:35.429638Z","caller":"traceutil/trace.go:171","msg":"trace[1481742482] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2275; }","duration":"3.149429869s","start":"2026-05-25T22:12:32.28019Z","end":"2026-05-25T22:12:35.429619Z","steps":["trace[1481742482] 'agreement among raft nodes before linearized reading' (duration: 3.149269164s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.429686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:32.28016Z","time spent":"3.149512821s","remote":"127.0.0.1:46954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5451,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-25T22:12:35.429476Z","caller":"traceutil/trace.go:171","msg":"trace[1946414945] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2275; }","duration":"231.129364ms","start":"2026-05-25T22:12:35.198317Z","end":"2026-05-25T22:12:35.429447Z","steps":["trace[1946414945] 'agreement among raft nodes before linearized reading' (duration: 230.877927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.429481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:31.969739Z","time spent":"3.459727996s","remote":"127.0.0.1:35320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-05-25T22:12:35.429558Z","caller":"traceutil/trace.go:171","msg":"trace[457518962] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2275; }","duration":"214.705686ms","start":"2026-05-25T22:12:35.214823Z","end":"2026-05-25T22:12:35.429528Z","steps":["trace[457518962] 'agreement among raft nodes before linearized reading' (duration: 214.260394ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:35.429133Z","caller":"traceutil/trace.go:171","msg":"trace[2086491337] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:2275; }","duration":"2.236712701s","start":"2026-05-25T22:12:33.192408Z","end":"2026-05-25T22:12:35.429121Z","steps":["trace[2086491337] 'agreement among raft nodes before linearized reading' (duration: 2.236622368s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.430148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.707171994s","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-25T22:12:35.430164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.612288723s","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-25T22:12:35.430204Z","caller":"traceutil/trace.go:171","msg":"trace[222978650] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2275; }","duration":"3.707284547s","start":"2026-05-25T22:12:31.722905Z","end":"2026-05-25T22:12:35.43019Z","steps":["trace[222978650] 'agreement among raft nodes before linearized reading' (duration: 3.706015761s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.430183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:33.192384Z","time spent":"2.237765571s","remote":"127.0.0.1:57394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:12:35.430247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:31.722881Z","time spent":"3.707356369s","remote":"127.0.0.1:40076","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":"info","ts":"2026-05-25T22:12:35.43025Z","caller":"traceutil/trace.go:171","msg":"trace[2123927597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2275; }","duration":"3.612441737s","start":"2026-05-25T22:12:31.817785Z","end":"2026-05-25T22:12:35.430227Z","steps":["trace[2123927597] 'agreement among raft nodes before linearized reading' (duration: 3.611465169s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.430276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.153463484s","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-25T22:12:35.430305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:31.817769Z","time spent":"3.61252328s","remote":"127.0.0.1:40076","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":"info","ts":"2026-05-25T22:12:35.430347Z","caller":"traceutil/trace.go:171","msg":"trace[1019530744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2275; }","duration":"2.153546566s","start":"2026-05-25T22:12:33.276762Z","end":"2026-05-25T22:12:35.430309Z","steps":["trace[1019530744] 'agreement among raft nodes before linearized reading' (duration: 2.153013681s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.430378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:33.276732Z","time spent":"2.153637649s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-25T22:12:35.430817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.633681148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T22:12:35.43093Z","caller":"traceutil/trace.go:171","msg":"trace[68969714] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2275; }","duration":"3.633998667s","start":"2026-05-25T22:12:31.796894Z","end":"2026-05-25T22:12:35.430893Z","steps":["trace[68969714] 'agreement among raft nodes before linearized reading' (duration: 3.632535787s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.431436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:12:31.796882Z","time spent":"3.634534002s","remote":"127.0.0.1:40294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":11,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:12:35.567264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.734324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18b2ee9416fc1252\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-05-25T22:12:35.567354Z","caller":"traceutil/trace.go:171","msg":"trace[1026595829] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18b2ee9416fc1252; range_end:; response_count:1; response_revision:2275; }","duration":"132.841578ms","start":"2026-05-25T22:12:35.434476Z","end":"2026-05-25T22:12:35.567318Z","steps":["trace[1026595829] 'range keys from in-memory index tree' (duration: 132.592391ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:35.567483Z","caller":"traceutil/trace.go:171","msg":"trace[1259935601] transaction","detail":"{read_only:false; response_revision:2276; number_of_response:1; }","duration":"132.503298ms","start":"2026-05-25T22:12:35.434936Z","end":"2026-05-25T22:12:35.567439Z","steps":["trace[1259935601] 'process raft request' (duration: 58.964581ms)","trace[1259935601] 'compare' (duration: 73.030143ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:12:35.567698Z","caller":"traceutil/trace.go:171","msg":"trace[2143994713] transaction","detail":"{read_only:false; response_revision:2278; number_of_response:1; }","duration":"131.230583ms","start":"2026-05-25T22:12:35.436455Z","end":"2026-05-25T22:12:35.567685Z","steps":["trace[2143994713] 'process raft request' (duration: 131.020957ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:35.567716Z","caller":"traceutil/trace.go:171","msg":"trace[957997463] transaction","detail":"{read_only:false; response_revision:2277; number_of_response:1; }","duration":"131.383307ms","start":"2026-05-25T22:12:35.436301Z","end":"2026-05-25T22:12:35.567684Z","steps":["trace[957997463] 'process raft request' (duration: 131.006656ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:35.567729Z","caller":"traceutil/trace.go:171","msg":"trace[1458877882] linearizableReadLoop","detail":"{readStateIndex:2364; appliedIndex:2360; }","duration":"131.15012ms","start":"2026-05-25T22:12:35.436553Z","end":"2026-05-25T22:12:35.567703Z","steps":["trace[1458877882] 'read index received' (duration: 57.231543ms)","trace[1458877882] 'applied index is now lower than readState.Index' (duration: 73.917317ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:12:35.567753Z","caller":"traceutil/trace.go:171","msg":"trace[1876360050] transaction","detail":"{read_only:false; response_revision:2280; number_of_response:1; }","duration":"130.7758ms","start":"2026-05-25T22:12:35.436957Z","end":"2026-05-25T22:12:35.567733Z","steps":["trace[1876360050] 'process raft request' (duration: 130.643257ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:35.567749Z","caller":"traceutil/trace.go:171","msg":"trace[1605005828] transaction","detail":"{read_only:false; response_revision:2279; number_of_response:1; }","duration":"131.192092ms","start":"2026-05-25T22:12:35.436533Z","end":"2026-05-25T22:12:35.567725Z","steps":["trace[1605005828] 'process raft request' (duration: 131.013627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.567893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.322945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:12:35.567929Z","caller":"traceutil/trace.go:171","msg":"trace[892538421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2280; }","duration":"131.389758ms","start":"2026-05-25T22:12:35.436532Z","end":"2026-05-25T22:12:35.567922Z","steps":["trace[892538421] 'agreement among raft nodes before linearized reading' (duration: 131.266594ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:35.7633Z","caller":"traceutil/trace.go:171","msg":"trace[982879766] linearizableReadLoop","detail":"{readStateIndex:2366; appliedIndex:2365; }","duration":"193.717193ms","start":"2026-05-25T22:12:35.569559Z","end":"2026-05-25T22:12:35.763277Z","steps":["trace[982879766] 'read index received' (duration: 119.822725ms)","trace[982879766] 'applied index is now lower than readState.Index' (duration: 73.893348ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:12:35.763405Z","caller":"traceutil/trace.go:171","msg":"trace[1032885049] transaction","detail":"{read_only:false; response_revision:2281; number_of_response:1; }","duration":"193.880637ms","start":"2026-05-25T22:12:35.569491Z","end":"2026-05-25T22:12:35.763372Z","steps":["trace[1032885049] 'process raft request' (duration: 119.878197ms)","trace[1032885049] 'compare' (duration: 73.814375ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:12:35.76353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.445951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T22:12:35.763582Z","caller":"traceutil/trace.go:171","msg":"trace[1309998259] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2281; }","duration":"181.546794ms","start":"2026-05-25T22:12:35.582015Z","end":"2026-05-25T22:12:35.763562Z","steps":["trace[1309998259] 'agreement among raft nodes before linearized reading' (duration: 181.399039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:35.763531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.043716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-25T22:12:35.763638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.030001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:12:35.763755Z","caller":"traceutil/trace.go:171","msg":"trace[717340953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2281; }","duration":"194.217776ms","start":"2026-05-25T22:12:35.569513Z","end":"2026-05-25T22:12:35.763731Z","steps":["trace[717340953] 'agreement among raft nodes before linearized reading' (duration: 193.912208ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:35.763684Z","caller":"traceutil/trace.go:171","msg":"trace[293579743] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2281; }","duration":"104.209331ms","start":"2026-05-25T22:12:35.659429Z","end":"2026-05-25T22:12:35.763639Z","steps":["trace[293579743] 'agreement among raft nodes before linearized reading' (duration: 104.042856ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:37.106235Z","caller":"traceutil/trace.go:171","msg":"trace[399886371] transaction","detail":"{read_only:false; response_revision:2284; number_of_response:1; }","duration":"134.779091ms","start":"2026-05-25T22:12:36.971426Z","end":"2026-05-25T22:12:37.106205Z","steps":["trace[399886371] 'process raft request' (duration: 41.946697ms)","trace[399886371] 'compare' (duration: 92.584408ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:12:37.106559Z","caller":"traceutil/trace.go:171","msg":"trace[1507067976] transaction","detail":"{read_only:false; response_revision:2285; number_of_response:1; }","duration":"135.07236ms","start":"2026-05-25T22:12:36.971462Z","end":"2026-05-25T22:12:37.106534Z","steps":["trace[1507067976] 'process raft request' (duration: 134.672068ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:37.106722Z","caller":"traceutil/trace.go:171","msg":"trace[961202362] transaction","detail":"{read_only:false; response_revision:2286; number_of_response:1; }","duration":"135.199184ms","start":"2026-05-25T22:12:36.971516Z","end":"2026-05-25T22:12:37.106715Z","steps":["trace[961202362] 'process raft request' (duration: 134.854244ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:37.106887Z","caller":"traceutil/trace.go:171","msg":"trace[1035613195] transaction","detail":"{read_only:false; response_revision:2287; number_of_response:1; }","duration":"135.198334ms","start":"2026-05-25T22:12:36.971668Z","end":"2026-05-25T22:12:37.106866Z","steps":["trace[1035613195] 'process raft request' (duration: 134.963297ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:37.107652Z","caller":"traceutil/trace.go:171","msg":"trace[1727225828] transaction","detail":"{read_only:false; response_revision:2289; number_of_response:1; }","duration":"128.868357ms","start":"2026-05-25T22:12:36.978763Z","end":"2026-05-25T22:12:37.107631Z","steps":["trace[1727225828] 'process raft request' (duration: 128.793345ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:37.107834Z","caller":"traceutil/trace.go:171","msg":"trace[1508116075] transaction","detail":"{read_only:false; response_revision:2288; number_of_response:1; }","duration":"135.693437ms","start":"2026-05-25T22:12:36.97211Z","end":"2026-05-25T22:12:37.107803Z","steps":["trace[1508116075] 'process raft request' (duration: 134.675989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:37.640704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.425387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T22:12:37.640924Z","caller":"traceutil/trace.go:171","msg":"trace[1152755952] transaction","detail":"{read_only:false; response_revision:2291; number_of_response:1; }","duration":"205.283214ms","start":"2026-05-25T22:12:37.435613Z","end":"2026-05-25T22:12:37.640897Z","steps":["trace[1152755952] 'process raft request' (duration: 77.576219ms)","trace[1152755952] 'compare' (duration: 127.271613ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:12:37.640936Z","caller":"traceutil/trace.go:171","msg":"trace[1555068701] linearizableReadLoop","detail":"{readStateIndex:2379; appliedIndex:2376; }","duration":"166.353101ms","start":"2026-05-25T22:12:37.474554Z","end":"2026-05-25T22:12:37.640907Z","steps":["trace[1555068701] 'read index received' (duration: 38.587444ms)","trace[1555068701] 'applied index is now lower than readState.Index' (duration: 127.764807ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:12:37.641032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.479245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T22:12:37.641027Z","caller":"traceutil/trace.go:171","msg":"trace[1124274981] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"204.829372ms","start":"2026-05-25T22:12:37.436176Z","end":"2026-05-25T22:12:37.641005Z","steps":["trace[1124274981] 'process raft request' (duration: 204.633797ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:37.641095Z","caller":"traceutil/trace.go:171","msg":"trace[1999001558] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2293; }","duration":"166.537626ms","start":"2026-05-25T22:12:37.474539Z","end":"2026-05-25T22:12:37.641076Z","steps":["trace[1999001558] 'agreement among raft nodes before linearized reading' (duration: 166.422193ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:37.641075Z","caller":"traceutil/trace.go:171","msg":"trace[1805317017] transaction","detail":"{read_only:false; response_revision:2293; number_of_response:1; }","duration":"204.784361ms","start":"2026-05-25T22:12:37.436249Z","end":"2026-05-25T22:12:37.641033Z","steps":["trace[1805317017] 'process raft request' (duration: 204.615656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:37.641299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.042832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5428"} {"level":"info","ts":"2026-05-25T22:12:37.641368Z","caller":"traceutil/trace.go:171","msg":"trace[614049661] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2293; }","duration":"143.154335ms","start":"2026-05-25T22:12:37.498195Z","end":"2026-05-25T22:12:37.64135Z","steps":["trace[614049661] 'agreement among raft nodes before linearized reading' (duration: 142.949789ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:39.871909Z","caller":"traceutil/trace.go:171","msg":"trace[847950315] linearizableReadLoop","detail":"{readStateIndex:2391; appliedIndex:2390; }","duration":"172.833761ms","start":"2026-05-25T22:12:39.699041Z","end":"2026-05-25T22:12:39.871875Z","steps":["trace[847950315] 'read index received' (duration: 145.331136ms)","trace[847950315] 'applied index is now lower than readState.Index' (duration: 27.501305ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:12:39.872021Z","caller":"traceutil/trace.go:171","msg":"trace[140982534] transaction","detail":"{read_only:false; response_revision:2305; number_of_response:1; }","duration":"175.608558ms","start":"2026-05-25T22:12:39.696335Z","end":"2026-05-25T22:12:39.871943Z","steps":["trace[140982534] 'process raft request' (duration: 148.042971ms)","trace[140982534] 'compare' (duration: 27.31492ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:12:39.872312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.246833ms","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-25T22:12:39.872369Z","caller":"traceutil/trace.go:171","msg":"trace[1818401218] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2305; }","duration":"173.351566ms","start":"2026-05-25T22:12:39.699003Z","end":"2026-05-25T22:12:39.872355Z","steps":["trace[1818401218] 'agreement among raft nodes before linearized reading' (duration: 173.090068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:39.872413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.102449ms","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-25T22:12:39.872466Z","caller":"traceutil/trace.go:171","msg":"trace[813712977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2305; }","duration":"173.214711ms","start":"2026-05-25T22:12:39.699238Z","end":"2026-05-25T22:12:39.872453Z","steps":["trace[813712977] 'agreement among raft nodes before linearized reading' (duration: 172.990785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:39.872752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.280103ms","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-25T22:12:39.872859Z","caller":"traceutil/trace.go:171","msg":"trace[718960187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2305; }","duration":"173.51359ms","start":"2026-05-25T22:12:39.699324Z","end":"2026-05-25T22:12:39.872837Z","steps":["trace[718960187] 'agreement among raft nodes before linearized reading' (duration: 172.869092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:12:39.872885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.615683ms","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-25T22:12:39.872972Z","caller":"traceutil/trace.go:171","msg":"trace[1367103582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2305; }","duration":"173.726926ms","start":"2026-05-25T22:12:39.699232Z","end":"2026-05-25T22:12:39.872959Z","steps":["trace[1367103582] 'agreement among raft nodes before linearized reading' (duration: 172.914013ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:12:40.081759Z","caller":"traceutil/trace.go:171","msg":"trace[1934633368] transaction","detail":"{read_only:false; response_revision:2310; number_of_response:1; }","duration":"149.676877ms","start":"2026-05-25T22:12:39.932049Z","end":"2026-05-25T22:12:40.081726Z","steps":["trace[1934633368] 'process raft request' (duration: 149.44458ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:17:43.439933Z","caller":"traceutil/trace.go:171","msg":"trace[152166926] linearizableReadLoop","detail":"{readStateIndex:5075; appliedIndex:5074; }","duration":"135.860113ms","start":"2026-05-25T22:17:43.30404Z","end":"2026-05-25T22:17:43.4399Z","steps":["trace[152166926] 'read index received' (duration: 135.644257ms)","trace[152166926] 'applied index is now lower than readState.Index' (duration: 214.376µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:17:43.440034Z","caller":"traceutil/trace.go:171","msg":"trace[983735161] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"139.965747ms","start":"2026-05-25T22:17:43.300041Z","end":"2026-05-25T22:17:43.440007Z","steps":["trace[983735161] 'process raft request' (duration: 139.668619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:17:43.440161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.090769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T22:17:43.440213Z","caller":"traceutil/trace.go:171","msg":"trace[982836099] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:4891; }","duration":"136.197343ms","start":"2026-05-25T22:17:43.304001Z","end":"2026-05-25T22:17:43.440199Z","steps":["trace[982836099] 'agreement among raft nodes before linearized reading' (duration: 136.077989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:17:43.440426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.936299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-25T22:17:43.44059Z","caller":"traceutil/trace.go:171","msg":"trace[1368370677] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4891; }","duration":"111.188616ms","start":"2026-05-25T22:17:43.329373Z","end":"2026-05-25T22:17:43.440562Z","steps":["trace[1368370677] 'agreement among raft nodes before linearized reading' (duration: 110.768324ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:17:46.129509Z","caller":"traceutil/trace.go:171","msg":"trace[1170578175] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"125.681009ms","start":"2026-05-25T22:17:46.003792Z","end":"2026-05-25T22:17:46.129473Z","steps":["trace[1170578175] 'process raft request' (duration: 125.418252ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:17:46.139981Z","caller":"traceutil/trace.go:171","msg":"trace[1150626805] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"135.647846ms","start":"2026-05-25T22:17:46.004309Z","end":"2026-05-25T22:17:46.139957Z","steps":["trace[1150626805] 'process raft request' (duration: 135.365788ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:18:01.008381Z","caller":"traceutil/trace.go:171","msg":"trace[1572462011] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"134.627638ms","start":"2026-05-25T22:18:00.873728Z","end":"2026-05-25T22:18:01.008356Z","steps":["trace[1572462011] 'process raft request' (duration: 134.445523ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:18:01.008576Z","caller":"traceutil/trace.go:171","msg":"trace[124391417] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"131.165392ms","start":"2026-05-25T22:18:00.877387Z","end":"2026-05-25T22:18:01.008552Z","steps":["trace[124391417] 'process raft request' (duration: 130.928345ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:18:03.125673Z","caller":"traceutil/trace.go:171","msg":"trace[633765586] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"109.323504ms","start":"2026-05-25T22:18:03.016328Z","end":"2026-05-25T22:18:03.125652Z","steps":["trace[633765586] 'process raft request' (duration: 109.282583ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:18:03.125719Z","caller":"traceutil/trace.go:171","msg":"trace[947268671] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"111.280269ms","start":"2026-05-25T22:18:03.014402Z","end":"2026-05-25T22:18:03.125682Z","steps":["trace[947268671] 'process raft request' (duration: 95.429687ms)","trace[947268671] 'compare' (duration: 15.658637ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:18:05.822377Z","caller":"traceutil/trace.go:171","msg":"trace[180548630] linearizableReadLoop","detail":"{readStateIndex:5546; appliedIndex:5545; }","duration":"130.496404ms","start":"2026-05-25T22:18:05.691851Z","end":"2026-05-25T22:18:05.822348Z","steps":["trace[180548630] 'read index received' (duration: 130.406291ms)","trace[180548630] 'applied index is now lower than readState.Index' (duration: 89.083µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:18:05.822518Z","caller":"traceutil/trace.go:171","msg":"trace[1072922139] transaction","detail":"{read_only:false; response_revision:5350; number_of_response:1; }","duration":"211.828018ms","start":"2026-05-25T22:18:05.610674Z","end":"2026-05-25T22:18:05.822502Z","steps":["trace[1072922139] 'process raft request' (duration: 211.503229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:18:05.822774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.915205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-25T22:18:05.822869Z","caller":"traceutil/trace.go:171","msg":"trace[1213487165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5350; }","duration":"131.060119ms","start":"2026-05-25T22:18:05.691795Z","end":"2026-05-25T22:18:05.822855Z","steps":["trace[1213487165] 'agreement among raft nodes before linearized reading' (duration: 130.799022ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:18:05.881767Z","caller":"traceutil/trace.go:171","msg":"trace[82588609] transaction","detail":"{read_only:false; response_revision:5351; number_of_response:1; }","duration":"101.240489ms","start":"2026-05-25T22:18:05.780479Z","end":"2026-05-25T22:18:05.88172Z","steps":["trace[82588609] 'process raft request' (duration: 70.02893ms)","trace[82588609] 'compare' (duration: 31.039774ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:18:05.882113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.880837ms","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-25T22:18:05.882156Z","caller":"traceutil/trace.go:171","msg":"trace[2068219604] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5352; }","duration":"110.97378ms","start":"2026-05-25T22:18:05.771171Z","end":"2026-05-25T22:18:05.882145Z","steps":["trace[2068219604] 'agreement among raft nodes before linearized reading' (duration: 110.540048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:18:05.88215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.540016ms","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-25T22:18:05.882221Z","caller":"traceutil/trace.go:171","msg":"trace[1589279453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5352; }","duration":"120.66167ms","start":"2026-05-25T22:18:05.761543Z","end":"2026-05-25T22:18:05.882205Z","steps":["trace[1589279453] 'agreement among raft nodes before linearized reading' (duration: 120.150415ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:18:10.021165Z","caller":"traceutil/trace.go:171","msg":"trace[405274178] transaction","detail":"{read_only:false; response_revision:5465; number_of_response:1; }","duration":"122.998034ms","start":"2026-05-25T22:18:09.898137Z","end":"2026-05-25T22:18:10.021135Z","steps":["trace[405274178] 'process raft request' (duration: 31.25572ms)","trace[405274178] 'compare' (duration: 91.496798ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:18:10.369437Z","caller":"traceutil/trace.go:171","msg":"trace[993601107] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"101.351251ms","start":"2026-05-25T22:18:10.268061Z","end":"2026-05-25T22:18:10.369412Z","steps":["trace[993601107] 'process raft request' (duration: 101.113555ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:18:10.947359Z","caller":"traceutil/trace.go:171","msg":"trace[273822375] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"179.863108ms","start":"2026-05-25T22:18:10.767463Z","end":"2026-05-25T22:18:10.947326Z","steps":["trace[273822375] 'process raft request' (duration: 179.668952ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:18:22.547365Z","caller":"traceutil/trace.go:171","msg":"trace[554585151] linearizableReadLoop","detail":"{readStateIndex:5831; appliedIndex:5828; }","duration":"113.182201ms","start":"2026-05-25T22:18:22.43416Z","end":"2026-05-25T22:18:22.547343Z","steps":["trace[554585151] 'read index received' (duration: 21.280432ms)","trace[554585151] 'applied index is now lower than readState.Index' (duration: 91.900989ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:18:22.547434Z","caller":"traceutil/trace.go:171","msg":"trace[376079167] transaction","detail":"{read_only:false; response_revision:5625; number_of_response:1; }","duration":"139.486894ms","start":"2026-05-25T22:18:22.407919Z","end":"2026-05-25T22:18:22.547406Z","steps":["trace[376079167] 'process raft request' (duration: 47.616556ms)","trace[376079167] 'compare' (duration: 91.5839ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:18:22.547708Z","caller":"traceutil/trace.go:171","msg":"trace[646048485] transaction","detail":"{read_only:false; response_revision:5627; number_of_response:1; }","duration":"139.375511ms","start":"2026-05-25T22:18:22.408316Z","end":"2026-05-25T22:18:22.547691Z","steps":["trace[646048485] 'process raft request' (duration: 138.98184ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:18:22.547697Z","caller":"traceutil/trace.go:171","msg":"trace[1036295191] transaction","detail":"{read_only:false; response_revision:5626; number_of_response:1; }","duration":"139.451323ms","start":"2026-05-25T22:18:22.408211Z","end":"2026-05-25T22:18:22.547662Z","steps":["trace[1036295191] 'process raft request' (duration: 139.029291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:18:22.547911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.741417ms","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-25T22:18:22.547989Z","caller":"traceutil/trace.go:171","msg":"trace[1680352732] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5627; }","duration":"113.884431ms","start":"2026-05-25T22:18:22.434086Z","end":"2026-05-25T22:18:22.547971Z","steps":["trace[1680352732] 'agreement among raft nodes before linearized reading' (duration: 113.350676ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:18:34.494064Z","caller":"traceutil/trace.go:171","msg":"trace[1352624200] transaction","detail":"{read_only:false; response_revision:5709; number_of_response:1; }","duration":"134.509515ms","start":"2026-05-25T22:18:34.359524Z","end":"2026-05-25T22:18:34.494034Z","steps":["trace[1352624200] 'process raft request' (duration: 134.350961ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:18:35.405653Z","caller":"traceutil/trace.go:171","msg":"trace[699977740] linearizableReadLoop","detail":"{readStateIndex:5918; appliedIndex:5917; }","duration":"121.341299ms","start":"2026-05-25T22:18:35.284286Z","end":"2026-05-25T22:18:35.405627Z","steps":["trace[699977740] 'read index received' (duration: 43.845757ms)","trace[699977740] 'applied index is now lower than readState.Index' (duration: 77.494162ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:18:35.40579Z","caller":"traceutil/trace.go:171","msg":"trace[1439286953] transaction","detail":"{read_only:false; response_revision:5712; number_of_response:1; }","duration":"130.306698ms","start":"2026-05-25T22:18:35.275455Z","end":"2026-05-25T22:18:35.405761Z","steps":["trace[1439286953] 'process raft request' (duration: 123.815938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:18:35.406172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.859694ms","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-25T22:18:35.40625Z","caller":"traceutil/trace.go:171","msg":"trace[1038379631] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5712; }","duration":"121.987937ms","start":"2026-05-25T22:18:35.284241Z","end":"2026-05-25T22:18:35.406229Z","steps":["trace[1038379631] 'agreement among raft nodes before linearized reading' (duration: 121.514464ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:19:03.284071Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2939} {"level":"warn","ts":"2026-05-25T22:19:03.67114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.301005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"warn","ts":"2026-05-25T22:19:03.671214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.480776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:19:03.671246Z","caller":"traceutil/trace.go:171","msg":"trace[1883105253] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5971; }","duration":"378.46082ms","start":"2026-05-25T22:19:03.292765Z","end":"2026-05-25T22:19:03.671225Z","steps":["trace[1883105253] 'range keys from in-memory index tree' (duration: 378.050418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:19:03.671297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:19:03.292746Z","time spent":"378.541591ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1844,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-05-25T22:19:03.671301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.822141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6157"} {"level":"info","ts":"2026-05-25T22:19:03.671353Z","caller":"traceutil/trace.go:171","msg":"trace[854045286] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5971; }","duration":"288.896653ms","start":"2026-05-25T22:19:03.382434Z","end":"2026-05-25T22:19:03.671331Z","steps":["trace[854045286] 'range keys from in-memory index tree' (duration: 288.662586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:19:03.671335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.536728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:19:03.671293Z","caller":"traceutil/trace.go:171","msg":"trace[1582093165] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5971; }","duration":"290.700552ms","start":"2026-05-25T22:19:03.380574Z","end":"2026-05-25T22:19:03.671274Z","steps":["trace[1582093165] 'range keys from in-memory index tree' (duration: 290.356703ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:19:03.671426Z","caller":"traceutil/trace.go:171","msg":"trace[858370564] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5971; }","duration":"290.674922ms","start":"2026-05-25T22:19:03.380731Z","end":"2026-05-25T22:19:03.671406Z","steps":["trace[858370564] 'range keys from in-memory index tree' (duration: 290.470125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:19:03.711569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.150408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T22:19:03.711843Z","caller":"traceutil/trace.go:171","msg":"trace[177054723] transaction","detail":"{read_only:false; response_revision:5972; number_of_response:1; }","duration":"221.837ms","start":"2026-05-25T22:19:03.489955Z","end":"2026-05-25T22:19:03.711792Z","steps":["trace[177054723] 'compare' (duration: 173.001834ms)","trace[177054723] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/08db1feb.percona.com; req_size:507; } (duration: 40.023411ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:19:03.712067Z","caller":"traceutil/trace.go:171","msg":"trace[938382919] transaction","detail":"{read_only:false; response_revision:5973; number_of_response:1; }","duration":"161.550806ms","start":"2026-05-25T22:19:03.550486Z","end":"2026-05-25T22:19:03.712037Z","steps":["trace[938382919] 'process raft request' (duration: 161.183426ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:19:03.712232Z","caller":"traceutil/trace.go:171","msg":"trace[1910847258] transaction","detail":"{read_only:false; response_revision:5975; number_of_response:1; }","duration":"111.593078ms","start":"2026-05-25T22:19:03.600577Z","end":"2026-05-25T22:19:03.71217Z","steps":["trace[1910847258] 'process raft request' (duration: 111.514166ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:19:03.712262Z","caller":"traceutil/trace.go:171","msg":"trace[418040906] transaction","detail":"{read_only:false; response_revision:5974; number_of_response:1; }","duration":"144.728648ms","start":"2026-05-25T22:19:03.567512Z","end":"2026-05-25T22:19:03.712241Z","steps":["trace[418040906] 'process raft request' (duration: 144.42281ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:19:03.79844Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2939,"took":"513.005765ms","hash":3712147897,"current-db-size-bytes":57540608,"current-db-size":"58 MB","current-db-size-in-use-bytes":33693696,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-25T22:19:03.798511Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3712147897,"revision":2939,"compact-revision":-1} {"level":"warn","ts":"2026-05-25T22:20:56.081647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.857375ms","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-25T22:20:56.081739Z","caller":"traceutil/trace.go:171","msg":"trace[1144434450] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:6689; }","duration":"680.971948ms","start":"2026-05-25T22:20:55.40075Z","end":"2026-05-25T22:20:56.081722Z","steps":["trace[1144434450] 'count revisions from in-memory index tree' (duration: 680.7012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:20:56.081774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:20:55.400692Z","time spent":"681.07256ms","remote":"127.0.0.1:40108","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-25T22:20:56.081789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.692679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:20:56.081929Z","caller":"traceutil/trace.go:171","msg":"trace[868343995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6689; }","duration":"508.902405ms","start":"2026-05-25T22:20:55.573Z","end":"2026-05-25T22:20:56.081903Z","steps":["trace[868343995] 'range keys from in-memory index tree' (duration: 508.541175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:20:56.081973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:20:55.572971Z","time spent":"508.992088ms","remote":"127.0.0.1:39838","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-25T22:20:56.082751Z","caller":"traceutil/trace.go:171","msg":"trace[1193163412] transaction","detail":"{read_only:false; response_revision:6690; number_of_response:1; }","duration":"386.645506ms","start":"2026-05-25T22:20:55.696068Z","end":"2026-05-25T22:20:56.082713Z","steps":["trace[1193163412] 'process raft request' (duration: 385.046381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:20:56.082896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:20:55.696038Z","time spent":"386.768779ms","remote":"127.0.0.1:40076","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-25T22:24:03.289321Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5970} {"level":"info","ts":"2026-05-25T22:24:03.391382Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5970,"took":"99.92041ms","hash":1822444171,"current-db-size-bytes":57540608,"current-db-size":"58 MB","current-db-size-in-use-bytes":21676032,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-25T22:24:03.391464Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1822444171,"revision":5970,"compact-revision":2939} {"level":"info","ts":"2026-05-25T22:24:06.177237Z","caller":"traceutil/trace.go:171","msg":"trace[127227193] transaction","detail":"{read_only:false; response_revision:8061; number_of_response:1; }","duration":"101.938797ms","start":"2026-05-25T22:24:06.075262Z","end":"2026-05-25T22:24:06.177201Z","steps":["trace[127227193] 'process raft request' (duration: 101.719231ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:24:42.942225Z","caller":"traceutil/trace.go:171","msg":"trace[1265828004] transaction","detail":"{read_only:false; response_revision:8430; number_of_response:1; }","duration":"210.873088ms","start":"2026-05-25T22:24:42.731335Z","end":"2026-05-25T22:24:42.942208Z","steps":["trace[1265828004] 'process raft request' (duration: 210.784576ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:24:53.354224Z","caller":"traceutil/trace.go:171","msg":"trace[1480762862] transaction","detail":"{read_only:false; response_revision:8487; number_of_response:1; }","duration":"120.771337ms","start":"2026-05-25T22:24:53.233414Z","end":"2026-05-25T22:24:53.354185Z","steps":["trace[1480762862] 'process raft request' (duration: 120.709675ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:24:53.354344Z","caller":"traceutil/trace.go:171","msg":"trace[637058028] transaction","detail":"{read_only:false; response_revision:8486; number_of_response:1; }","duration":"137.571052ms","start":"2026-05-25T22:24:53.216722Z","end":"2026-05-25T22:24:53.354293Z","steps":["trace[637058028] 'process raft request' (duration: 37.509849ms)","trace[637058028] 'compare' (duration: 99.704933ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:24:55.120641Z","caller":"traceutil/trace.go:171","msg":"trace[507912516] linearizableReadLoop","detail":"{readStateIndex:8793; appliedIndex:8792; }","duration":"371.225098ms","start":"2026-05-25T22:24:54.749388Z","end":"2026-05-25T22:24:55.120613Z","steps":["trace[507912516] 'read index received' (duration: 371.000422ms)","trace[507912516] 'applied index is now lower than readState.Index' (duration: 223.516µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:24:55.120717Z","caller":"traceutil/trace.go:171","msg":"trace[1013564587] transaction","detail":"{read_only:false; response_revision:8494; number_of_response:1; }","duration":"396.701684ms","start":"2026-05-25T22:24:54.723978Z","end":"2026-05-25T22:24:55.12068Z","steps":["trace[1013564587] 'process raft request' (duration: 396.470477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:24:55.120834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.420573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T22:24:55.120909Z","caller":"traceutil/trace.go:171","msg":"trace[1818663415] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:8494; }","duration":"371.542677ms","start":"2026-05-25T22:24:54.74935Z","end":"2026-05-25T22:24:55.120893Z","steps":["trace[1818663415] 'agreement among raft nodes before linearized reading' (duration: 371.398493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:24:55.120913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.047992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-5cc4cd9d9d-42lp7.18b2ef433dda309f\" ","response":"range_response_count:1 size:1135"} {"level":"warn","ts":"2026-05-25T22:24:55.120918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:24:54.723945Z","time spent":"396.869698ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:24:55.120942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:24:54.74934Z","time spent":"371.593508ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"info","ts":"2026-05-25T22:24:55.120959Z","caller":"traceutil/trace.go:171","msg":"trace[1207213206] range","detail":"{range_begin:/registry/events/openstack/barbican-api-5cc4cd9d9d-42lp7.18b2ef433dda309f; range_end:; response_count:1; response_revision:8494; }","duration":"275.151555ms","start":"2026-05-25T22:24:54.845793Z","end":"2026-05-25T22:24:55.120944Z","steps":["trace[1207213206] 'agreement among raft nodes before linearized reading' (duration: 274.96991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:24:55.121013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.684602ms","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-25T22:24:55.12109Z","caller":"traceutil/trace.go:171","msg":"trace[851219435] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8494; }","duration":"121.803186ms","start":"2026-05-25T22:24:54.999271Z","end":"2026-05-25T22:24:55.121074Z","steps":["trace[851219435] 'agreement among raft nodes before linearized reading' (duration: 121.562449ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:24:55.46497Z","caller":"traceutil/trace.go:171","msg":"trace[1588230971] transaction","detail":"{read_only:false; response_revision:8497; number_of_response:1; }","duration":"303.560533ms","start":"2026-05-25T22:24:55.161384Z","end":"2026-05-25T22:24:55.464945Z","steps":["trace[1588230971] 'process raft request' (duration: 303.476481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:24:55.465132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:24:55.16135Z","time spent":"303.704446ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:24:55.465174Z","caller":"traceutil/trace.go:171","msg":"trace[341433544] transaction","detail":"{read_only:false; response_revision:8498; number_of_response:1; }","duration":"301.136586ms","start":"2026-05-25T22:24:55.164Z","end":"2026-05-25T22:24:55.465137Z","steps":["trace[341433544] 'process raft request' (duration: 300.910699ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:24:55.464987Z","caller":"traceutil/trace.go:171","msg":"trace[1805565537] transaction","detail":"{read_only:false; response_revision:8496; number_of_response:1; }","duration":"339.045286ms","start":"2026-05-25T22:24:55.125914Z","end":"2026-05-25T22:24:55.464959Z","steps":["trace[1805565537] 'process raft request' (duration: 338.800879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:24:55.465326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:24:55.125878Z","time spent":"339.390545ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:24:55.46537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:24:55.163976Z","time spent":"301.28407ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:24:55.617028Z","caller":"traceutil/trace.go:171","msg":"trace[391792835] transaction","detail":"{read_only:false; response_revision:8499; number_of_response:1; }","duration":"257.729552ms","start":"2026-05-25T22:24:55.359264Z","end":"2026-05-25T22:24:55.616994Z","steps":["trace[391792835] 'process raft request' (duration: 199.297663ms)","trace[391792835] 'compare' (duration: 58.075699ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:24:55.617391Z","caller":"traceutil/trace.go:171","msg":"trace[2091554114] transaction","detail":"{read_only:false; response_revision:8500; number_of_response:1; }","duration":"256.056366ms","start":"2026-05-25T22:24:55.361326Z","end":"2026-05-25T22:24:55.617382Z","steps":["trace[2091554114] 'process raft request' (duration: 255.615323ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:25:29.920315Z","caller":"traceutil/trace.go:171","msg":"trace[1338256688] transaction","detail":"{read_only:false; response_revision:8733; number_of_response:1; }","duration":"230.771135ms","start":"2026-05-25T22:25:29.689519Z","end":"2026-05-25T22:25:29.92029Z","steps":["trace[1338256688] 'process raft request' (duration: 230.57388ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:25:29.920335Z","caller":"traceutil/trace.go:171","msg":"trace[1439367413] transaction","detail":"{read_only:false; response_revision:8734; number_of_response:1; }","duration":"182.117597ms","start":"2026-05-25T22:25:29.738199Z","end":"2026-05-25T22:25:29.920317Z","steps":["trace[1439367413] 'process raft request' (duration: 182.039825ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:25:29.920524Z","caller":"traceutil/trace.go:171","msg":"trace[1369772313] linearizableReadLoop","detail":"{readStateIndex:9042; appliedIndex:9041; }","duration":"216.908021ms","start":"2026-05-25T22:25:29.703588Z","end":"2026-05-25T22:25:29.920496Z","steps":["trace[1369772313] 'read index received' (duration: 42.806616ms)","trace[1369772313] 'applied index is now lower than readState.Index' (duration: 174.099585ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:25:29.92056Z","caller":"traceutil/trace.go:171","msg":"trace[1325538801] transaction","detail":"{read_only:false; response_revision:8735; number_of_response:1; }","duration":"181.344475ms","start":"2026-05-25T22:25:29.739188Z","end":"2026-05-25T22:25:29.920533Z","steps":["trace[1325538801] 'process raft request' (duration: 181.095738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:25:29.920774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.163538ms","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-25T22:25:29.920861Z","caller":"traceutil/trace.go:171","msg":"trace[253529567] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8735; }","duration":"217.303602ms","start":"2026-05-25T22:25:29.703537Z","end":"2026-05-25T22:25:29.920841Z","steps":["trace[253529567] 'agreement among raft nodes before linearized reading' (duration: 217.063855ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:25:38.026279Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023a3.wal"} {"level":"info","ts":"2026-05-25T22:25:41.979947Z","caller":"traceutil/trace.go:171","msg":"trace[1362800534] transaction","detail":"{read_only:false; response_revision:8843; number_of_response:1; }","duration":"191.334873ms","start":"2026-05-25T22:25:41.788591Z","end":"2026-05-25T22:25:41.979926Z","steps":["trace[1362800534] 'process raft request' (duration: 191.134767ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:26:47.13857Z","caller":"traceutil/trace.go:171","msg":"trace[204239254] transaction","detail":"{read_only:false; response_revision:9527; number_of_response:1; }","duration":"114.945625ms","start":"2026-05-25T22:26:47.023595Z","end":"2026-05-25T22:26:47.138541Z","steps":["trace[204239254] 'process raft request' (duration: 112.773535ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:26:56.578541Z","caller":"traceutil/trace.go:171","msg":"trace[1568253947] transaction","detail":"{read_only:false; response_revision:9578; number_of_response:1; }","duration":"324.669047ms","start":"2026-05-25T22:26:56.253824Z","end":"2026-05-25T22:26:56.578493Z","steps":["trace[1568253947] 'process raft request' (duration: 324.39541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:26:56.57883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:56.253794Z","time spent":"324.889914ms","remote":"127.0.0.1:40076","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-25T22:26:57.685871Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057962080,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:26:58.186506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057962080,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:26:58.687268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057962080,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:26:59.188487Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057962080,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:26:59.886987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057962080,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:27:00.440195Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057962080,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:27:00.733402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000014197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-25T22:27:00.739709Z","caller":"traceutil/trace.go:171","msg":"trace[1537921922] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.006351403s","start":"2026-05-25T22:26:58.733332Z","end":"2026-05-25T22:27:00.739683Z","steps":["trace[1537921922] 'agreement among raft nodes before linearized reading' (duration: 2.000021167s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.739784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:58.733326Z","time spent":"2.006444225s","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-25T22:27:00.881521Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.229535448s","expected-duration":"1s"} {"level":"info","ts":"2026-05-25T22:27:00.882011Z","caller":"traceutil/trace.go:171","msg":"trace[279423382] transaction","detail":"{read_only:false; response_revision:9584; number_of_response:1; }","duration":"3.756382518s","start":"2026-05-25T22:26:57.125608Z","end":"2026-05-25T22:27:00.88199Z","steps":["trace[279423382] 'process raft request' (duration: 3.756233554s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.882284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:57.125577Z","time spent":"3.756652046s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:27:00.914635Z","caller":"traceutil/trace.go:171","msg":"trace[1906623751] transaction","detail":"{read_only:false; response_revision:9585; number_of_response:1; }","duration":"3.719633219s","start":"2026-05-25T22:26:57.194981Z","end":"2026-05-25T22:27:00.914614Z","steps":["trace[1906623751] 'process raft request' (duration: 3.7142461s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:27:00.914674Z","caller":"traceutil/trace.go:171","msg":"trace[382850458] transaction","detail":"{read_only:false; response_revision:9586; number_of_response:1; }","duration":"2.182081991s","start":"2026-05-25T22:26:58.732571Z","end":"2026-05-25T22:27:00.914653Z","steps":["trace[382850458] 'process raft request' (duration: 2.181960938s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.914757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:57.194944Z","time spent":"3.719747602s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:27:00.91478Z","caller":"traceutil/trace.go:171","msg":"trace[1931715930] linearizableReadLoop","detail":"{readStateIndex:9926; appliedIndex:9919; }","duration":"3.729080051s","start":"2026-05-25T22:26:57.185685Z","end":"2026-05-25T22:27:00.914765Z","steps":["trace[1931715930] 'read index received' (duration: 3.695943933s)","trace[1931715930] 'applied index is now lower than readState.Index' (duration: 33.135298ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:27:00.91478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:58.73254Z","time spent":"2.182193385s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:27:00.914869Z","caller":"traceutil/trace.go:171","msg":"trace[920984013] transaction","detail":"{read_only:false; response_revision:9587; number_of_response:1; }","duration":"2.182099982s","start":"2026-05-25T22:26:58.732759Z","end":"2026-05-25T22:27:00.914859Z","steps":["trace[920984013] 'process raft request' (duration: 2.181816514s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:27:00.91492Z","caller":"traceutil/trace.go:171","msg":"trace[1220650796] transaction","detail":"{read_only:false; response_revision:9591; number_of_response:1; }","duration":"407.665286ms","start":"2026-05-25T22:27:00.507244Z","end":"2026-05-25T22:27:00.914909Z","steps":["trace[1220650796] 'process raft request' (duration: 407.457561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.914992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:00.507213Z","time spent":"407.743659ms","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":819,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T22:27:00.915042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.729358879s","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-25T22:27:00.91508Z","caller":"traceutil/trace.go:171","msg":"trace[763807424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9591; }","duration":"3.729458192s","start":"2026-05-25T22:26:57.185611Z","end":"2026-05-25T22:27:00.91507Z","steps":["trace[763807424] 'agreement among raft nodes before linearized reading' (duration: 3.729339399s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.915107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:57.185567Z","time spent":"3.729532374s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-25T22:27:00.915192Z","caller":"traceutil/trace.go:171","msg":"trace[1813661840] transaction","detail":"{read_only:false; response_revision:9588; number_of_response:1; }","duration":"2.182310248s","start":"2026-05-25T22:26:58.73287Z","end":"2026-05-25T22:27:00.91518Z","steps":["trace[1813661840] 'process raft request' (duration: 2.181736862s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.915233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.981258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T22:27:00.91525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.718621241s","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-25T22:27:00.915276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:58.732861Z","time spent":"2.182367439s","remote":"127.0.0.1:40076","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-25T22:27:00.915312Z","caller":"traceutil/trace.go:171","msg":"trace[635750855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9591; }","duration":"175.03998ms","start":"2026-05-25T22:27:00.740209Z","end":"2026-05-25T22:27:00.915249Z","steps":["trace[635750855] 'agreement among raft nodes before linearized reading' (duration: 175.001959ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:27:00.915321Z","caller":"traceutil/trace.go:171","msg":"trace[692196461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9591; }","duration":"3.718748134s","start":"2026-05-25T22:26:57.196556Z","end":"2026-05-25T22:27:00.915304Z","steps":["trace[692196461] 'agreement among raft nodes before linearized reading' (duration: 3.718493627s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.915358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:57.196545Z","time spent":"3.718806457s","remote":"127.0.0.1:40076","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-25T22:27:00.915346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.641151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:27:00.91542Z","caller":"traceutil/trace.go:171","msg":"trace[287745062] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:9591; }","duration":"868.763315ms","start":"2026-05-25T22:27:00.046636Z","end":"2026-05-25T22:27:00.9154Z","steps":["trace[287745062] 'agreement among raft nodes before linearized reading' (duration: 868.668252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.915455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:00.04661Z","time spent":"868.836217ms","remote":"127.0.0.1:57502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:27:00.915488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.333849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:27:00.915528Z","caller":"traceutil/trace.go:171","msg":"trace[1712106830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9591; }","duration":"144.404081ms","start":"2026-05-25T22:27:00.771113Z","end":"2026-05-25T22:27:00.915518Z","steps":["trace[1712106830] 'agreement among raft nodes before linearized reading' (duration: 144.3501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.915544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.183505421s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:27:00.915569Z","caller":"traceutil/trace.go:171","msg":"trace[654695831] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:9591; }","duration":"2.183771438s","start":"2026-05-25T22:26:58.731788Z","end":"2026-05-25T22:27:00.91556Z","steps":["trace[654695831] 'agreement among raft nodes before linearized reading' (duration: 2.183723767s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.915586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412432221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:27:00.915588Z","caller":"traceutil/trace.go:171","msg":"trace[1063989101] transaction","detail":"{read_only:false; response_revision:9589; number_of_response:1; }","duration":"2.180720804s","start":"2026-05-25T22:26:58.734853Z","end":"2026-05-25T22:27:00.915574Z","steps":["trace[1063989101] 'process raft request' (duration: 2.179783358s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:27:00.915613Z","caller":"traceutil/trace.go:171","msg":"trace[1304257128] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:9591; }","duration":"1.412513634s","start":"2026-05-25T22:26:59.503092Z","end":"2026-05-25T22:27:00.915606Z","steps":["trace[1304257128] 'agreement among raft nodes before linearized reading' (duration: 1.412469522s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.915641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:59.503066Z","time spent":"1.412568044s","remote":"127.0.0.1:35398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:27:00.915757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:58.734841Z","time spent":"2.180794516s","remote":"127.0.0.1:40076","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-25T22:27:00.9158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.921485791s","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-25T22:27:00.915823Z","caller":"traceutil/trace.go:171","msg":"trace[1966750310] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:9591; }","duration":"2.921570904s","start":"2026-05-25T22:26:57.994246Z","end":"2026-05-25T22:27:00.915817Z","steps":["trace[1966750310] 'agreement among raft nodes before linearized reading' (duration: 2.921469081s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.915842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:57.994215Z","time spent":"2.921621655s","remote":"127.0.0.1:40166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":76,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:27:00.915499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.18274006s","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-25T22:27:00.915894Z","caller":"traceutil/trace.go:171","msg":"trace[1255860794] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9591; }","duration":"2.183156511s","start":"2026-05-25T22:26:58.732726Z","end":"2026-05-25T22:27:00.915882Z","steps":["trace[1255860794] 'agreement among raft nodes before linearized reading' (duration: 2.182724589s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.915664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:58.731762Z","time spent":"2.183892121s","remote":"127.0.0.1:57480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:27:00.915929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.755105948s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zndct\" ","response":"range_response_count:1 size:6530"} {"level":"warn","ts":"2026-05-25T22:27:00.915935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:58.732715Z","time spent":"2.183207652s","remote":"127.0.0.1:40076","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-25T22:27:00.915955Z","caller":"traceutil/trace.go:171","msg":"trace[677955232] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zndct; range_end:; response_count:1; response_revision:9591; }","duration":"1.755156199s","start":"2026-05-25T22:26:59.160793Z","end":"2026-05-25T22:27:00.915949Z","steps":["trace[677955232] 'agreement among raft nodes before linearized reading' (duration: 1.755075707s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.915975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:59.160783Z","time spent":"1.755186851s","remote":"127.0.0.1:39972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-zndct\" "} {"level":"warn","ts":"2026-05-25T22:27:00.916092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.986468373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-05-25T22:27:00.916117Z","caller":"traceutil/trace.go:171","msg":"trace[779603991] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:9591; }","duration":"2.986543415s","start":"2026-05-25T22:26:57.929564Z","end":"2026-05-25T22:27:00.916108Z","steps":["trace[779603991] 'agreement among raft nodes before linearized reading' (duration: 2.986466272s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.916136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:57.929532Z","time spent":"2.986599216s","remote":"127.0.0.1:39988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2013,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-05-25T22:27:00.915932Z","caller":"traceutil/trace.go:171","msg":"trace[1995387750] transaction","detail":"{read_only:false; response_revision:9590; number_of_response:1; }","duration":"1.990601384s","start":"2026-05-25T22:26:58.925303Z","end":"2026-05-25T22:27:00.915905Z","steps":["trace[1995387750] 'process raft request' (duration: 1.98936725s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.916202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:58.925268Z","time spent":"1.990899442s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:27:00.91524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.75444789s","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-25T22:27:00.916256Z","caller":"traceutil/trace.go:171","msg":"trace[1387523834] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9591; }","duration":"1.755588091s","start":"2026-05-25T22:26:59.160659Z","end":"2026-05-25T22:27:00.916247Z","steps":["trace[1387523834] 'agreement among raft nodes before linearized reading' (duration: 1.754390068s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.916256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.688216338s","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-25T22:27:00.916278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:59.160618Z","time spent":"1.755652713s","remote":"127.0.0.1:39972","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-25T22:27:00.914931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:58.732703Z","time spent":"2.182188844s","remote":"127.0.0.1:40076","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-25T22:27:00.916355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.286665801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T22:27:00.916373Z","caller":"traceutil/trace.go:171","msg":"trace[781484670] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:9591; }","duration":"3.286685173s","start":"2026-05-25T22:26:57.629681Z","end":"2026-05-25T22:27:00.916367Z","steps":["trace[781484670] 'agreement among raft nodes before linearized reading' (duration: 3.286610491s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.916391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:57.629599Z","time spent":"3.286786725s","remote":"127.0.0.1:39962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":59,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-05-25T22:27:00.916279Z","caller":"traceutil/trace.go:171","msg":"trace[1705927579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9591; }","duration":"3.68827656s","start":"2026-05-25T22:26:57.227996Z","end":"2026-05-25T22:27:00.916272Z","steps":["trace[1705927579] 'agreement among raft nodes before linearized reading' (duration: 3.688206978s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.916462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:57.227979Z","time spent":"3.688469406s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-25T22:27:00.916532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.465024184s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-05-25T22:27:00.916551Z","caller":"traceutil/trace.go:171","msg":"trace[1553924589] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:9591; }","duration":"3.465091826s","start":"2026-05-25T22:26:57.451452Z","end":"2026-05-25T22:27:00.916544Z","steps":["trace[1553924589] 'agreement among raft nodes before linearized reading' (duration: 3.465030324s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:00.916568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:26:57.45143Z","time spent":"3.465132137s","remote":"127.0.0.1:39956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1656,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-05-25T22:27:10.362833Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058057962205,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:27:10.364636Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.937072616s","expected-duration":"1s"} {"level":"info","ts":"2026-05-25T22:27:10.410807Z","caller":"traceutil/trace.go:171","msg":"trace[1759078786] transaction","detail":"{read_only:false; response_revision:9631; number_of_response:1; }","duration":"1.98331038s","start":"2026-05-25T22:27:08.427465Z","end":"2026-05-25T22:27:10.410775Z","steps":["trace[1759078786] 'process raft request' (duration: 1.93755716s)","trace[1759078786] 'compare' (duration: 44.435444ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T22:27:10.410984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:08.427438Z","time spent":"1.983446774s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:27:10.416732Z","caller":"traceutil/trace.go:171","msg":"trace[51222605] transaction","detail":"{read_only:false; response_revision:9632; number_of_response:1; }","duration":"1.276265536s","start":"2026-05-25T22:27:09.140417Z","end":"2026-05-25T22:27:10.416682Z","steps":["trace[51222605] 'process raft request' (duration: 1.275951197s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:27:10.41678Z","caller":"traceutil/trace.go:171","msg":"trace[101031804] transaction","detail":"{read_only:false; response_revision:9633; number_of_response:1; }","duration":"1.276050159s","start":"2026-05-25T22:27:09.140698Z","end":"2026-05-25T22:27:10.416748Z","steps":["trace[101031804] 'process raft request' (duration: 1.275880925s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:10.416913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:09.140399Z","time spent":"1.2764167s","remote":"127.0.0.1:40076","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-25T22:27:10.417003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:09.140693Z","time spent":"1.276178814s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:27:10.417105Z","caller":"traceutil/trace.go:171","msg":"trace[512430354] linearizableReadLoop","detail":"{readStateIndex:9974; appliedIndex:9968; }","duration":"1.719004049s","start":"2026-05-25T22:27:08.698091Z","end":"2026-05-25T22:27:10.417095Z","steps":["trace[512430354] 'read index received' (duration: 1.666946893s)","trace[512430354] 'applied index is now lower than readState.Index' (duration: 52.054346ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:27:10.41719Z","caller":"traceutil/trace.go:171","msg":"trace[575311694] transaction","detail":"{read_only:false; response_revision:9634; number_of_response:1; }","duration":"1.27642404s","start":"2026-05-25T22:27:09.140748Z","end":"2026-05-25T22:27:10.417172Z","steps":["trace[575311694] 'process raft request' (duration: 1.275869525s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:10.417243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:09.140745Z","time spent":"1.276469621s","remote":"127.0.0.1:40076","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-25T22:27:10.417467Z","caller":"traceutil/trace.go:171","msg":"trace[205584263] transaction","detail":"{read_only:false; response_revision:9636; number_of_response:1; }","duration":"1.085538851s","start":"2026-05-25T22:27:09.331919Z","end":"2026-05-25T22:27:10.417458Z","steps":["trace[205584263] 'process raft request' (duration: 1.08476738s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:10.417512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:09.331895Z","time spent":"1.085592523s","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5074,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:27:10.417532Z","caller":"traceutil/trace.go:171","msg":"trace[887593093] transaction","detail":"{read_only:false; response_revision:9635; number_of_response:1; }","duration":"1.276670097s","start":"2026-05-25T22:27:09.140795Z","end":"2026-05-25T22:27:10.417465Z","steps":["trace[887593093] 'process raft request' (duration: 1.275857165s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:10.417676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.719580985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T22:27:10.417709Z","caller":"traceutil/trace.go:171","msg":"trace[241611963] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:9636; }","duration":"1.719656858s","start":"2026-05-25T22:27:08.698041Z","end":"2026-05-25T22:27:10.417698Z","steps":["trace[241611963] 'agreement among raft nodes before linearized reading' (duration: 1.719575886s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:10.417731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:08.698017Z","time spent":"1.719708228s","remote":"127.0.0.1:40558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":31,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:27:10.41779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:09.140792Z","time spent":"1.276853372s","remote":"127.0.0.1:40076","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-25T22:27:10.417888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.719523234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:27:10.417913Z","caller":"traceutil/trace.go:171","msg":"trace[1249420607] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:9636; }","duration":"1.719575485s","start":"2026-05-25T22:27:08.698329Z","end":"2026-05-25T22:27:10.417904Z","steps":["trace[1249420607] 'agreement among raft nodes before linearized reading' (duration: 1.719509344s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:10.417934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:08.698318Z","time spent":"1.719609376s","remote":"127.0.0.1:35298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:27:10.41813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277345766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-25T22:27:10.418129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277304834s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-25T22:27:10.418182Z","caller":"traceutil/trace.go:171","msg":"trace[171065893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9636; }","duration":"1.277362676s","start":"2026-05-25T22:27:09.140808Z","end":"2026-05-25T22:27:10.418171Z","steps":["trace[171065893] 'agreement among raft nodes before linearized reading' (duration: 1.277186891s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:10.418208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277436829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-25T22:27:10.41823Z","caller":"traceutil/trace.go:171","msg":"trace[1502256122] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9636; }","duration":"1.277464749s","start":"2026-05-25T22:27:09.14076Z","end":"2026-05-25T22:27:10.418225Z","steps":["trace[1502256122] 'agreement among raft nodes before linearized reading' (duration: 1.277403958s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:10.41824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:09.140806Z","time spent":"1.277415177s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-25T22:27:10.418248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:09.140758Z","time spent":"1.27748574s","remote":"127.0.0.1:40076","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-25T22:27:10.418409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.639255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-05-25T22:27:10.418422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277812549s","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-25T22:27:10.418443Z","caller":"traceutil/trace.go:171","msg":"trace[2037124473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9636; }","duration":"1.277840229s","start":"2026-05-25T22:27:09.140596Z","end":"2026-05-25T22:27:10.418437Z","steps":["trace[2037124473] 'agreement among raft nodes before linearized reading' (duration: 1.277776437s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:27:10.418441Z","caller":"traceutil/trace.go:171","msg":"trace[1923055799] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:9636; }","duration":"190.687606ms","start":"2026-05-25T22:27:10.227745Z","end":"2026-05-25T22:27:10.418432Z","steps":["trace[1923055799] 'agreement among raft nodes before linearized reading' (duration: 190.577633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:10.418461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:09.140594Z","time spent":"1.27786244s","remote":"127.0.0.1:40076","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-25T22:27:10.418573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.910495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:27:10.418604Z","caller":"traceutil/trace.go:171","msg":"trace[1360003353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9636; }","duration":"279.970697ms","start":"2026-05-25T22:27:10.138624Z","end":"2026-05-25T22:27:10.418595Z","steps":["trace[1360003353] 'agreement among raft nodes before linearized reading' (duration: 279.907915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:10.418587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.712734184s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/glance-db-sync-9ppq9\" ","response":"range_response_count:1 size:7004"} {"level":"info","ts":"2026-05-25T22:27:10.420539Z","caller":"traceutil/trace.go:171","msg":"trace[1600988352] range","detail":"{range_begin:/registry/pods/openstack/glance-db-sync-9ppq9; range_end:; response_count:1; response_revision:9636; }","duration":"1.714685619s","start":"2026-05-25T22:27:08.705821Z","end":"2026-05-25T22:27:10.420506Z","steps":["trace[1600988352] 'agreement among raft nodes before linearized reading' (duration: 1.712679263s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:10.420643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:08.705805Z","time spent":"1.714804702s","remote":"127.0.0.1:39972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":7027,"request content":"key:\"/registry/pods/openstack/glance-db-sync-9ppq9\" "} {"level":"info","ts":"2026-05-25T22:27:10.418153Z","caller":"traceutil/trace.go:171","msg":"trace[2030302669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9636; }","duration":"1.277372976s","start":"2026-05-25T22:27:09.140773Z","end":"2026-05-25T22:27:10.418146Z","steps":["trace[2030302669] 'agreement among raft nodes before linearized reading' (duration: 1.277269823s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:10.420722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:09.14077Z","time spent":"1.279941737s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-25T22:27:10.418889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.275645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:27:10.420824Z","caller":"traceutil/trace.go:171","msg":"trace[968832277] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:9636; }","duration":"309.259009ms","start":"2026-05-25T22:27:10.111557Z","end":"2026-05-25T22:27:10.420816Z","steps":["trace[968832277] 'agreement among raft nodes before linearized reading' (duration: 307.257014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:10.420847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:10.111543Z","time spent":"309.294271ms","remote":"127.0.0.1:50566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:27:10.419011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278414835s","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-25T22:27:10.420953Z","caller":"traceutil/trace.go:171","msg":"trace[184737183] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9636; }","duration":"1.28037495s","start":"2026-05-25T22:27:09.140573Z","end":"2026-05-25T22:27:10.420948Z","steps":["trace[184737183] 'agreement among raft nodes before linearized reading' (duration: 1.278383805s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:10.420973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:09.140568Z","time spent":"1.28039666s","remote":"127.0.0.1:40076","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":"warn","ts":"2026-05-25T22:27:10.419009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278857928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zndct\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-25T22:27:10.421022Z","caller":"traceutil/trace.go:171","msg":"trace[1297301358] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zndct; range_end:; response_count:1; response_revision:9636; }","duration":"1.280898665s","start":"2026-05-25T22:27:09.140115Z","end":"2026-05-25T22:27:10.421014Z","steps":["trace[1297301358] 'agreement among raft nodes before linearized reading' (duration: 1.278799397s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:10.421038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:09.1401Z","time spent":"1.280932365s","remote":"127.0.0.1:39972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-zndct\" "} {"level":"warn","ts":"2026-05-25T22:27:10.419067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.652065448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-25T22:27:10.42109Z","caller":"traceutil/trace.go:171","msg":"trace[20862369] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:9636; }","duration":"1.654132415s","start":"2026-05-25T22:27:08.766947Z","end":"2026-05-25T22:27:10.42108Z","steps":["trace[20862369] 'agreement among raft nodes before linearized reading' (duration: 1.651994015s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:27:10.421117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:27:08.766933Z","time spent":"1.654175026s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-05-25T22:27:12.533596Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-25T22:27:12.540128Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-25T22:27:12.540222Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-25T22:29:03.29544Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8048} {"level":"info","ts":"2026-05-25T22:29:03.357991Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8048,"took":"60.536604ms","hash":3601406124,"current-db-size-bytes":57540608,"current-db-size":"58 MB","current-db-size-in-use-bytes":27705344,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-25T22:29:03.358127Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3601406124,"revision":8048,"compact-revision":5970} {"level":"info","ts":"2026-05-25T22:34:03.300763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10388} {"level":"info","ts":"2026-05-25T22:34:03.382256Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10388,"took":"79.75967ms","hash":2563140924,"current-db-size-bytes":57540608,"current-db-size":"58 MB","current-db-size-in-use-bytes":25882624,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-25T22:34:03.382322Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2563140924,"revision":10388,"compact-revision":8048} {"level":"info","ts":"2026-05-25T22:39:03.307418Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12567} {"level":"info","ts":"2026-05-25T22:39:03.383785Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12567,"took":"74.224456ms","hash":2131452661,"current-db-size-bytes":57540608,"current-db-size":"58 MB","current-db-size-in-use-bytes":27717632,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-25T22:39:03.38386Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2131452661,"revision":12567,"compact-revision":10388} {"level":"info","ts":"2026-05-25T22:39:17.615395Z","caller":"traceutil/trace.go:171","msg":"trace[2045560600] transaction","detail":"{read_only:false; response_revision:14667; number_of_response:1; }","duration":"184.605919ms","start":"2026-05-25T22:39:17.430758Z","end":"2026-05-25T22:39:17.615364Z","steps":["trace[2045560600] 'process raft request' (duration: 143.327072ms)","trace[2045560600] 'compare' (duration: 41.064771ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:39:26.51349Z","caller":"traceutil/trace.go:171","msg":"trace[689755851] transaction","detail":"{read_only:false; response_revision:14710; number_of_response:1; }","duration":"141.42955ms","start":"2026-05-25T22:39:26.372036Z","end":"2026-05-25T22:39:26.513466Z","steps":["trace[689755851] 'process raft request' (duration: 141.212754ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:39:35.111676Z","caller":"traceutil/trace.go:171","msg":"trace[2100854411] transaction","detail":"{read_only:false; response_revision:14768; number_of_response:1; }","duration":"277.383282ms","start":"2026-05-25T22:39:34.834208Z","end":"2026-05-25T22:39:35.111592Z","steps":["trace[2100854411] 'process raft request' (duration: 276.92493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:39:59.730046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.806664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:39:59.730136Z","caller":"traceutil/trace.go:171","msg":"trace[1863321971] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:15093; }","duration":"104.014639ms","start":"2026-05-25T22:39:59.626104Z","end":"2026-05-25T22:39:59.730118Z","steps":["trace[1863321971] 'count revisions from in-memory index tree' (duration: 103.548906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:39:59.730738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.392452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f39ea7ec-60a9-410b-8a38-0c19b55d0846\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-05-25T22:39:59.730844Z","caller":"traceutil/trace.go:171","msg":"trace[1218067627] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f39ea7ec-60a9-410b-8a38-0c19b55d0846; range_end:; response_count:1; response_revision:15093; }","duration":"107.563266ms","start":"2026-05-25T22:39:59.623256Z","end":"2026-05-25T22:39:59.730819Z","steps":["trace[1218067627] 'range keys from in-memory index tree' (duration: 107.161246ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:39:59.988458Z","caller":"traceutil/trace.go:171","msg":"trace[1256289671] transaction","detail":"{read_only:false; response_revision:15094; number_of_response:1; }","duration":"211.255776ms","start":"2026-05-25T22:39:59.777177Z","end":"2026-05-25T22:39:59.988433Z","steps":["trace[1256289671] 'process raft request' (duration: 211.106872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:41:26.218508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.739952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-25T22:41:26.218599Z","caller":"traceutil/trace.go:171","msg":"trace[763277321] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:15901; }","duration":"125.890946ms","start":"2026-05-25T22:41:26.09269Z","end":"2026-05-25T22:41:26.218581Z","steps":["trace[763277321] 'range keys from in-memory index tree' (duration: 125.538017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:41:26.218857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.195235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-25T22:41:26.218942Z","caller":"traceutil/trace.go:171","msg":"trace[1467031681] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:15901; }","duration":"126.323908ms","start":"2026-05-25T22:41:26.092597Z","end":"2026-05-25T22:41:26.218921Z","steps":["trace[1467031681] 'range keys from in-memory index tree' (duration: 125.962478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:41:42.538351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.969513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-05-25T22:41:42.538348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.653099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T22:41:42.538351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.949289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T22:41:42.538503Z","caller":"traceutil/trace.go:171","msg":"trace[1190298274] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15988; }","duration":"107.157395ms","start":"2026-05-25T22:41:42.431326Z","end":"2026-05-25T22:41:42.538484Z","steps":["trace[1190298274] 'range keys from in-memory index tree' (duration: 106.847227ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:41:42.538516Z","caller":"traceutil/trace.go:171","msg":"trace[1936577351] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15988; }","duration":"106.173438ms","start":"2026-05-25T22:41:42.432287Z","end":"2026-05-25T22:41:42.538461Z","steps":["trace[1936577351] 'range keys from in-memory index tree' (duration: 105.726296ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:41:42.538512Z","caller":"traceutil/trace.go:171","msg":"trace[1963405793] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15988; }","duration":"107.860515ms","start":"2026-05-25T22:41:42.430619Z","end":"2026-05-25T22:41:42.53848Z","steps":["trace[1963405793] 'range keys from in-memory index tree' (duration: 107.503685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:41:42.538537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.814399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:680"} {"level":"info","ts":"2026-05-25T22:41:42.538606Z","caller":"traceutil/trace.go:171","msg":"trace[2037142132] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15988; }","duration":"105.918642ms","start":"2026-05-25T22:41:42.432666Z","end":"2026-05-25T22:41:42.538585Z","steps":["trace[2037142132] 'range keys from in-memory index tree' (duration: 105.607493ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:44:03.31377Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14591} {"level":"info","ts":"2026-05-25T22:44:03.387264Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14591,"took":"71.21875ms","hash":2781931464,"current-db-size-bytes":57540608,"current-db-size":"58 MB","current-db-size-in-use-bytes":31342592,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-25T22:44:03.38733Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2781931464,"revision":14591,"compact-revision":12567} {"level":"info","ts":"2026-05-25T22:49:03.32132Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16950} {"level":"info","ts":"2026-05-25T22:49:03.417403Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16950,"took":"92.612088ms","hash":1980538130,"current-db-size-bytes":57540608,"current-db-size":"58 MB","current-db-size-in-use-bytes":30257152,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-25T22:49:03.417466Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1980538130,"revision":16950,"compact-revision":14591} {"level":"info","ts":"2026-05-25T22:49:57.877019Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-25T22:49:57.883222Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-25T22:49:57.883343Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-25T22:54:03.327965Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19034} {"level":"info","ts":"2026-05-25T22:54:03.417783Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19034,"took":"86.87481ms","hash":3808623661,"current-db-size-bytes":57540608,"current-db-size":"58 MB","current-db-size-in-use-bytes":24674304,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-25T22:54:03.417845Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3808623661,"revision":19034,"compact-revision":16950} {"level":"info","ts":"2026-05-25T22:54:41.533845Z","caller":"traceutil/trace.go:171","msg":"trace[1539283495] linearizableReadLoop","detail":"{readStateIndex:21519; appliedIndex:21517; }","duration":"230.170562ms","start":"2026-05-25T22:54:41.303644Z","end":"2026-05-25T22:54:41.533815Z","steps":["trace[1539283495] 'read index received' (duration: 229.840433ms)","trace[1539283495] 'applied index is now lower than readState.Index' (duration: 329.309µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:54:41.53431Z","caller":"traceutil/trace.go:171","msg":"trace[195118278] transaction","detail":"{read_only:false; response_revision:20787; number_of_response:1; }","duration":"257.516625ms","start":"2026-05-25T22:54:41.27678Z","end":"2026-05-25T22:54:41.534297Z","steps":["trace[195118278] 'process raft request' (duration: 256.793406ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:54:41.534504Z","caller":"traceutil/trace.go:171","msg":"trace[1807409292] transaction","detail":"{read_only:false; response_revision:20788; number_of_response:1; }","duration":"251.642266ms","start":"2026-05-25T22:54:41.282851Z","end":"2026-05-25T22:54:41.534493Z","steps":["trace[1807409292] 'process raft request' (duration: 250.929206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:41.534765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.100287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/pxc-operator\" ","response":"range_response_count:1 size:4386"} {"level":"info","ts":"2026-05-25T22:54:41.534809Z","caller":"traceutil/trace.go:171","msg":"trace[1032205699] range","detail":"{range_begin:/registry/deployments/openstack/pxc-operator; range_end:; response_count:1; response_revision:20788; }","duration":"231.216671ms","start":"2026-05-25T22:54:41.303583Z","end":"2026-05-25T22:54:41.534799Z","steps":["trace[1032205699] 'agreement among raft nodes before linearized reading' (duration: 231.020746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:41.535024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.241268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-25T22:54:41.535051Z","caller":"traceutil/trace.go:171","msg":"trace[1033589214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20788; }","duration":"204.3165ms","start":"2026-05-25T22:54:41.330728Z","end":"2026-05-25T22:54:41.535045Z","steps":["trace[1033589214] 'agreement among raft nodes before linearized reading' (duration: 204.238688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:41.535343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.602646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-25T22:54:41.535379Z","caller":"traceutil/trace.go:171","msg":"trace[1534459551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20788; }","duration":"120.681069ms","start":"2026-05-25T22:54:41.414687Z","end":"2026-05-25T22:54:41.535368Z","steps":["trace[1534459551] 'agreement among raft nodes before linearized reading' (duration: 120.602406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:41.535527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.150422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:5884"} {"level":"info","ts":"2026-05-25T22:54:41.535551Z","caller":"traceutil/trace.go:171","msg":"trace[837893018] range","detail":"{range_begin:/registry/deployments/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:20788; }","duration":"202.187902ms","start":"2026-05-25T22:54:41.333358Z","end":"2026-05-25T22:54:41.535546Z","steps":["trace[837893018] 'agreement among raft nodes before linearized reading' (duration: 202.1144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:41.535687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.087824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-25T22:54:41.53571Z","caller":"traceutil/trace.go:171","msg":"trace[1591447442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20788; }","duration":"204.127745ms","start":"2026-05-25T22:54:41.331576Z","end":"2026-05-25T22:54:41.535704Z","steps":["trace[1591447442] 'agreement among raft nodes before linearized reading' (duration: 204.063133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:42.08446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.417563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/rook-ceph-crashcollector-instance\" ","response":"range_response_count:1 size:9322"} {"level":"info","ts":"2026-05-25T22:54:42.084584Z","caller":"traceutil/trace.go:171","msg":"trace[2073536974] range","detail":"{range_begin:/registry/deployments/openstack/rook-ceph-crashcollector-instance; range_end:; response_count:1; response_revision:20791; }","duration":"292.613059ms","start":"2026-05-25T22:54:41.791941Z","end":"2026-05-25T22:54:42.084554Z","steps":["trace[2073536974] 'range keys from in-memory index tree' (duration: 292.184747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:42.084649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.017339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:6163"} {"level":"info","ts":"2026-05-25T22:54:42.084747Z","caller":"traceutil/trace.go:171","msg":"trace[1188237523] range","detail":"{range_begin:/registry/deployments/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:20791; }","duration":"413.179763ms","start":"2026-05-25T22:54:41.671538Z","end":"2026-05-25T22:54:42.084717Z","steps":["trace[1188237523] 'range keys from in-memory index tree' (duration: 412.805343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:42.084739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.549946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-05-25T22:54:42.084787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:41.671507Z","time spent":"413.270696ms","remote":"127.0.0.1:40294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":6186,"request content":"key:\"/registry/deployments/openstack/rabbitmq-messaging-topology-operator\" "} {"level":"info","ts":"2026-05-25T22:54:42.084798Z","caller":"traceutil/trace.go:171","msg":"trace[2089211993] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:20791; }","duration":"250.666159ms","start":"2026-05-25T22:54:41.834117Z","end":"2026-05-25T22:54:42.084783Z","steps":["trace[2089211993] 'range keys from in-memory index tree' (duration: 250.392332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:42.084977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.872223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-25T22:54:42.085039Z","caller":"traceutil/trace.go:171","msg":"trace[842899519] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:20791; }","duration":"255.028927ms","start":"2026-05-25T22:54:41.829994Z","end":"2026-05-25T22:54:42.085023Z","steps":["trace[842899519] 'range keys from in-memory index tree' (duration: 254.663748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:43.333848Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058058004204,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:54:43.834895Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058058004204,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:54:44.335312Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832058058004204,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T22:54:44.345134Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.599050668s","expected-duration":"1s"} {"level":"info","ts":"2026-05-25T22:54:44.345737Z","caller":"traceutil/trace.go:171","msg":"trace[1941976131] transaction","detail":"{read_only:false; response_revision:20795; number_of_response:1; }","duration":"1.599796078s","start":"2026-05-25T22:54:42.745917Z","end":"2026-05-25T22:54:44.345713Z","steps":["trace[1941976131] 'process raft request' (duration: 1.599612393s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.345969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:42.745821Z","time spent":"1.599993853s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:54:44.359419Z","caller":"traceutil/trace.go:171","msg":"trace[1398940746] transaction","detail":"{read_only:false; response_revision:20796; number_of_response:1; }","duration":"1.326624118s","start":"2026-05-25T22:54:43.032763Z","end":"2026-05-25T22:54:44.359387Z","steps":["trace[1398940746] 'process raft request' (duration: 1.326376441s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:54:44.359771Z","caller":"traceutil/trace.go:171","msg":"trace[225178915] transaction","detail":"{read_only:false; response_revision:20797; number_of_response:1; }","duration":"1.163423254s","start":"2026-05-25T22:54:43.196334Z","end":"2026-05-25T22:54:44.359758Z","steps":["trace[225178915] 'process raft request' (duration: 1.163007092s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.359844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.196304Z","time spent":"1.163508576s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T22:54:44.36017Z","caller":"traceutil/trace.go:171","msg":"trace[1198259546] linearizableReadLoop","detail":"{readStateIndex:21530; appliedIndex:21526; }","duration":"1.52659922s","start":"2026-05-25T22:54:42.83353Z","end":"2026-05-25T22:54:44.36013Z","steps":["trace[1198259546] 'read index received' (duration: 1.511909581s)","trace[1198259546] 'applied index is now lower than readState.Index' (duration: 14.686529ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T22:54:44.360289Z","caller":"traceutil/trace.go:171","msg":"trace[281029693] transaction","detail":"{read_only:false; response_revision:20798; number_of_response:1; }","duration":"722.145377ms","start":"2026-05-25T22:54:43.638134Z","end":"2026-05-25T22:54:44.36028Z","steps":["trace[281029693] 'process raft request' (duration: 721.574081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.360339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.638115Z","time spent":"722.197849ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:54:44.360631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.523186497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-hj859\" ","response":"range_response_count:1 size:14278"} {"level":"info","ts":"2026-05-25T22:54:44.360681Z","caller":"traceutil/trace.go:171","msg":"trace[1828238288] range","detail":"{range_begin:/registry/pods/openstack/neutron-metadata-agent-default-hj859; range_end:; response_count:1; response_revision:20798; }","duration":"1.523298439s","start":"2026-05-25T22:54:42.837373Z","end":"2026-05-25T22:54:44.360671Z","steps":["trace[1828238288] 'agreement among raft nodes before linearized reading' (duration: 1.523143445s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.360709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:42.837311Z","time spent":"1.523392732s","remote":"127.0.0.1:39972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":14301,"request content":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-hj859\" "} {"level":"warn","ts":"2026-05-25T22:54:44.360891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168640835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-25T22:54:44.360914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171600106s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-2kmx7\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-05-25T22:54:44.360934Z","caller":"traceutil/trace.go:171","msg":"trace[125261234] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:20798; }","duration":"1.168741728s","start":"2026-05-25T22:54:43.192179Z","end":"2026-05-25T22:54:44.360921Z","steps":["trace[125261234] 'agreement among raft nodes before linearized reading' (duration: 1.168663326s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:54:44.360935Z","caller":"traceutil/trace.go:171","msg":"trace[1788435324] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-2kmx7; range_end:; response_count:1; response_revision:20798; }","duration":"1.171629936s","start":"2026-05-25T22:54:43.189299Z","end":"2026-05-25T22:54:44.360929Z","steps":["trace[1788435324] 'agreement among raft nodes before linearized reading' (duration: 1.171550624s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.360956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.189294Z","time spent":"1.171657807s","remote":"127.0.0.1:39972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22124,"request content":"key:\"/registry/pods/openstack/nova-compute-default-2kmx7\" "} {"level":"warn","ts":"2026-05-25T22:54:44.360957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.192149Z","time spent":"1.16880126s","remote":"127.0.0.1:50534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:54:44.361103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172057178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-05-25T22:54:44.361128Z","caller":"traceutil/trace.go:171","msg":"trace[125058241] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20798; }","duration":"1.172146101s","start":"2026-05-25T22:54:43.188976Z","end":"2026-05-25T22:54:44.361122Z","steps":["trace[125058241] 'agreement among raft nodes before linearized reading' (duration: 1.172065879s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.361136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.105083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-25T22:54:44.361146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.188946Z","time spent":"1.172196072s","remote":"127.0.0.1:39962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-05-25T22:54:44.361163Z","caller":"traceutil/trace.go:171","msg":"trace[685489372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20798; }","duration":"723.139294ms","start":"2026-05-25T22:54:43.638017Z","end":"2026-05-25T22:54:44.361156Z","steps":["trace[685489372] 'agreement among raft nodes before linearized reading' (duration: 723.061732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.361185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.638011Z","time spent":"723.168664ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-25T22:54:44.361299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181957907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-25T22:54:44.361324Z","caller":"traceutil/trace.go:171","msg":"trace[837053536] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20798; }","duration":"1.181999579s","start":"2026-05-25T22:54:43.179319Z","end":"2026-05-25T22:54:44.361319Z","steps":["trace[837053536] 'agreement among raft nodes before linearized reading' (duration: 1.181927677s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.361343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.17931Z","time spent":"1.1820294s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-25T22:54:44.361366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.334699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-25T22:54:44.361391Z","caller":"traceutil/trace.go:171","msg":"trace[455697850] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:20798; }","duration":"723.571816ms","start":"2026-05-25T22:54:43.637812Z","end":"2026-05-25T22:54:44.361384Z","steps":["trace[455697850] 'agreement among raft nodes before linearized reading' (duration: 723.475083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.361412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.637787Z","time spent":"723.619176ms","remote":"127.0.0.1:40364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":43,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-25T22:54:44.361481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188278719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-l3-agent-default-pvpsg\" ","response":"range_response_count:1 size:18671"} {"level":"warn","ts":"2026-05-25T22:54:44.361409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.032723Z","time spent":"1.326793412s","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T22:54:44.361612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.24501707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"warn","ts":"2026-05-25T22:54:44.361624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.649648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-25T22:54:44.361635Z","caller":"traceutil/trace.go:171","msg":"trace[835071346] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20798; }","duration":"1.245097823s","start":"2026-05-25T22:54:43.116532Z","end":"2026-05-25T22:54:44.36163Z","steps":["trace[835071346] 'agreement among raft nodes before linearized reading' (duration: 1.245041511s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:54:44.361649Z","caller":"traceutil/trace.go:171","msg":"trace[1439511670] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20798; }","duration":"723.70677ms","start":"2026-05-25T22:54:43.637932Z","end":"2026-05-25T22:54:44.361639Z","steps":["trace[1439511670] 'agreement among raft nodes before linearized reading' (duration: 723.651318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.361677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.637923Z","time spent":"723.748991ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-25T22:54:44.361708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.303614922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-05-25T22:54:44.361729Z","caller":"traceutil/trace.go:171","msg":"trace[1295563727] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20798; }","duration":"1.303716755s","start":"2026-05-25T22:54:43.058006Z","end":"2026-05-25T22:54:44.361723Z","steps":["trace[1295563727] 'agreement among raft nodes before linearized reading' (duration: 1.303658253s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.361747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.057928Z","time spent":"1.303815598s","remote":"127.0.0.1:39962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-25T22:54:44.361791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.816403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-25T22:54:44.361815Z","caller":"traceutil/trace.go:171","msg":"trace[2073918480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20798; }","duration":"723.853183ms","start":"2026-05-25T22:54:43.637956Z","end":"2026-05-25T22:54:44.361809Z","steps":["trace[2073918480] 'agreement among raft nodes before linearized reading' (duration: 723.810912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.361837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.637949Z","time spent":"723.884644ms","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-25T22:54:44.361982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.318773094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-bzbvv\" ","response":"range_response_count:1 size:18646"} {"level":"info","ts":"2026-05-25T22:54:44.362009Z","caller":"traceutil/trace.go:171","msg":"trace[1545565052] range","detail":"{range_begin:/registry/pods/openstack/neutron-dhcp-agent-default-bzbvv; range_end:; response_count:1; response_revision:20798; }","duration":"1.318834216s","start":"2026-05-25T22:54:43.043169Z","end":"2026-05-25T22:54:44.362003Z","steps":["trace[1545565052] 'agreement among raft nodes before linearized reading' (duration: 1.318667681s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.362033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.043149Z","time spent":"1.318879257s","remote":"127.0.0.1:39972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":18669,"request content":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-bzbvv\" "} {"level":"warn","ts":"2026-05-25T22:54:44.362155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.231744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-25T22:54:44.362182Z","caller":"traceutil/trace.go:171","msg":"trace[1274861053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20798; }","duration":"724.371227ms","start":"2026-05-25T22:54:43.637805Z","end":"2026-05-25T22:54:44.362176Z","steps":["trace[1274861053] 'agreement among raft nodes before linearized reading' (duration: 724.295655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.36219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339440216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-m7tjh\" ","response":"range_response_count:1 size:20755"} {"level":"warn","ts":"2026-05-25T22:54:44.362206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.637776Z","time spent":"724.425379ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-25T22:54:44.362332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087926913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/staffeln-conductor\" ","response":"range_response_count:1 size:5116"} {"level":"info","ts":"2026-05-25T22:54:44.362357Z","caller":"traceutil/trace.go:171","msg":"trace[1540975333] range","detail":"{range_begin:/registry/deployments/openstack/staffeln-conductor; range_end:; response_count:1; response_revision:20798; }","duration":"1.087988235s","start":"2026-05-25T22:54:43.274362Z","end":"2026-05-25T22:54:44.36235Z","steps":["trace[1540975333] 'agreement among raft nodes before linearized reading' (duration: 1.087913203s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.362375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.27434Z","time spent":"1.088030916s","remote":"127.0.0.1:40294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5139,"request content":"key:\"/registry/deployments/openstack/staffeln-conductor\" "} {"level":"warn","ts":"2026-05-25T22:54:44.362537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150790791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-25T22:54:44.362564Z","caller":"traceutil/trace.go:171","msg":"trace[428502164] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:20798; }","duration":"1.150848523s","start":"2026-05-25T22:54:43.211709Z","end":"2026-05-25T22:54:44.362558Z","steps":["trace[428502164] 'agreement among raft nodes before linearized reading' (duration: 1.150768381s)"],"step_count":1} {"level":"info","ts":"2026-05-25T22:54:44.361505Z","caller":"traceutil/trace.go:171","msg":"trace[758002076] range","detail":"{range_begin:/registry/pods/openstack/neutron-l3-agent-default-pvpsg; range_end:; response_count:1; response_revision:20798; }","duration":"1.188363192s","start":"2026-05-25T22:54:43.173137Z","end":"2026-05-25T22:54:44.3615Z","steps":["trace[758002076] 'agreement among raft nodes before linearized reading' (duration: 1.18828982s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.361654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.116498Z","time spent":"1.245150504s","remote":"127.0.0.1:39962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-25T22:54:44.362769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.173105Z","time spent":"1.189618625s","remote":"127.0.0.1:39972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":18694,"request content":"key:\"/registry/pods/openstack/neutron-l3-agent-default-pvpsg\" "} {"level":"warn","ts":"2026-05-25T22:54:44.362895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161374319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-7k8f7\" ","response":"range_response_count:1 size:9792"} {"level":"info","ts":"2026-05-25T22:54:44.362925Z","caller":"traceutil/trace.go:171","msg":"trace[345031031] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-7k8f7; range_end:; response_count:1; response_revision:20798; }","duration":"1.161489222s","start":"2026-05-25T22:54:43.201429Z","end":"2026-05-25T22:54:44.362918Z","steps":["trace[345031031] 'agreement among raft nodes before linearized reading' (duration: 1.161409989s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.362948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.201401Z","time spent":"1.161539393s","remote":"127.0.0.1:39972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9815,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-7k8f7\" "} {"level":"warn","ts":"2026-05-25T22:54:44.362947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.529394285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/libvirt-libvirt-default-m7tjh.18b2f018c8f18317\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:20794 ","response":"range_response_count:794 size:616730"} {"level":"info","ts":"2026-05-25T22:54:44.363013Z","caller":"traceutil/trace.go:171","msg":"trace[840541984] range","detail":"{range_begin:/registry/events/openstack/libvirt-libvirt-default-m7tjh.18b2f018c8f18317\u0000; range_end:/registry/events/openstack0; response_count:794; response_revision:20798; }","duration":"1.52954386s","start":"2026-05-25T22:54:42.833452Z","end":"2026-05-25T22:54:44.362996Z","steps":["trace[840541984] 'agreement among raft nodes before linearized reading' (duration: 1.526904398s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.363084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:42.833421Z","time spent":"1.529625782s","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":794,"response size":616753,"request content":"key:\"/registry/events/openstack/libvirt-libvirt-default-m7tjh.18b2f018c8f18317\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:20794 "} {"level":"warn","ts":"2026-05-25T22:54:44.361569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.576606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-25T22:54:44.363127Z","caller":"traceutil/trace.go:171","msg":"trace[2019705748] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20798; }","duration":"725.134478ms","start":"2026-05-25T22:54:43.637984Z","end":"2026-05-25T22:54:44.363119Z","steps":["trace[2019705748] 'agreement among raft nodes before linearized reading' (duration: 723.532874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.363156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.637917Z","time spent":"725.230511ms","remote":"127.0.0.1:40076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-25T22:54:44.362212Z","caller":"traceutil/trace.go:171","msg":"trace[1706264897] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-m7tjh; range_end:; response_count:1; response_revision:20798; }","duration":"1.339515967s","start":"2026-05-25T22:54:43.022687Z","end":"2026-05-25T22:54:44.362203Z","steps":["trace[1706264897] 'agreement among raft nodes before linearized reading' (duration: 1.339449265s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.363261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.022658Z","time spent":"1.340584686s","remote":"127.0.0.1:39972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20778,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-m7tjh\" "} {"level":"warn","ts":"2026-05-25T22:54:44.361562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240034745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-05-25T22:54:44.363501Z","caller":"traceutil/trace.go:171","msg":"trace[1293431461] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20798; }","duration":"1.241963037s","start":"2026-05-25T22:54:43.1215Z","end":"2026-05-25T22:54:44.363463Z","steps":["trace[1293431461] 'agreement among raft nodes before linearized reading' (duration: 1.240018394s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:44.36354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.121489Z","time spent":"1.242040469s","remote":"127.0.0.1:39962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-25T22:54:44.364276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:43.211691Z","time spent":"1.152572829s","remote":"127.0.0.1:39902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-05-25T22:54:47.462911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.976811009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-25T22:54:47.463221Z","caller":"traceutil/trace.go:171","msg":"trace[1715372820] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:20804; }","duration":"1.977163479s","start":"2026-05-25T22:54:45.486038Z","end":"2026-05-25T22:54:47.463201Z","steps":["trace[1715372820] 'count revisions from in-memory index tree' (duration: 1.976735238s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T22:54:47.463297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T22:54:45.486022Z","time spent":"1.977228111s","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1796,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "}