{"level":"warn","ts":"2026-05-27T19:42:57.594941Z","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-27T19:42:57.595102Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.176:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.176:2380","--initial-cluster=instance=https://199.19.213.176:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.176:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.176: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-27T19:42:57.595363Z","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-27T19:42:57.595386Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.176:2380"]} {"level":"info","ts":"2026-05-27T19:42:57.595567Z","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-27T19:42:57.597739Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.176:2379"]} {"level":"info","ts":"2026-05-27T19:42:57.598787Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.176:2380"],"listen-peer-urls":["https://199.19.213.176:2380"],"advertise-client-urls":["https://199.19.213.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.176: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-27T19:42:57.603583Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.272759ms"} {"level":"info","ts":"2026-05-27T19:42:57.6096Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6e120e57916b9bd4","cluster-id":"e644555f29703ff6"} {"level":"info","ts":"2026-05-27T19:42:57.609813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T19:42:57.609848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became follower at term 0"} {"level":"info","ts":"2026-05-27T19:42:57.60987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6e120e57916b9bd4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T19:42:57.609891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became follower at term 1"} {"level":"info","ts":"2026-05-27T19:42:57.609951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 switched to configuration voters=(7931417663017556948)"} {"level":"warn","ts":"2026-05-27T19:42:57.615539Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T19:42:57.61711Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T19:42:57.618333Z","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-27T19:42:57.621544Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6e120e57916b9bd4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T19:42:57.621766Z","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-27T19:42:57.621931Z","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-27T19:42:57.621951Z","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-27T19:42:57.62183Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6e120e57916b9bd4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T19:42:57.623141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 switched to configuration voters=(7931417663017556948)"} {"level":"info","ts":"2026-05-27T19:42:57.623295Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e644555f29703ff6","local-member-id":"6e120e57916b9bd4","added-peer-id":"6e120e57916b9bd4","added-peer-peer-urls":["https://199.19.213.176:2380"]} {"level":"info","ts":"2026-05-27T19:42:57.627213Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.176:2380"} {"level":"info","ts":"2026-05-27T19:42:57.627066Z","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-27T19:42:57.62726Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.176:2380"} {"level":"info","ts":"2026-05-27T19:42:57.627668Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6e120e57916b9bd4","initial-advertise-peer-urls":["https://199.19.213.176:2380"],"listen-peer-urls":["https://199.19.213.176:2380"],"advertise-client-urls":["https://199.19.213.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T19:42:57.627734Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T19:42:58.410484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T19:42:58.410548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T19:42:58.410572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 received MsgPreVoteResp from 6e120e57916b9bd4 at term 1"} {"level":"info","ts":"2026-05-27T19:42:58.410592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became candidate at term 2"} {"level":"info","ts":"2026-05-27T19:42:58.410604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 received MsgVoteResp from 6e120e57916b9bd4 at term 2"} {"level":"info","ts":"2026-05-27T19:42:58.410621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e120e57916b9bd4 became leader at term 2"} {"level":"info","ts":"2026-05-27T19:42:58.410635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6e120e57916b9bd4 elected leader 6e120e57916b9bd4 at term 2"} {"level":"info","ts":"2026-05-27T19:42:58.412036Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6e120e57916b9bd4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.176:2379]}","request-path":"/0/members/6e120e57916b9bd4/attributes","cluster-id":"e644555f29703ff6","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T19:42:58.412076Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:42:58.41212Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:42:58.412381Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:42:58.412994Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T19:42:58.412998Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e644555f29703ff6","local-member-id":"6e120e57916b9bd4","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:42:58.413021Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T19:42:58.413074Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:42:58.413092Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:42:58.415679Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T19:42:58.415801Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.176:2379"} {"level":"info","ts":"2026-05-27T19:43:41.846273Z","caller":"traceutil/trace.go:171","msg":"trace[1363784868] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"125.973608ms","start":"2026-05-27T19:43:41.720251Z","end":"2026-05-27T19:43:41.846224Z","steps":["trace[1363784868] 'process raft request' (duration: 113.074682ms)","trace[1363784868] 'compare' (duration: 12.56039ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:43:42.288554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.248665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-27T19:43:42.288608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.16091ms","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-27T19:43:42.28868Z","caller":"traceutil/trace.go:171","msg":"trace[576054444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"297.267011ms","start":"2026-05-27T19:43:41.991392Z","end":"2026-05-27T19:43:42.288659Z","steps":["trace[576054444] 'range keys from in-memory index tree' (duration: 296.920995ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:42.288657Z","caller":"traceutil/trace.go:171","msg":"trace[156011309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"170.395737ms","start":"2026-05-27T19:43:42.118242Z","end":"2026-05-27T19:43:42.288638Z","steps":["trace[156011309] 'range keys from in-memory index tree' (duration: 170.109232ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:42.713518Z","caller":"traceutil/trace.go:171","msg":"trace[257789233] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"293.5129ms","start":"2026-05-27T19:43:42.419955Z","end":"2026-05-27T19:43:42.713468Z","steps":["trace[257789233] 'read index received' (duration: 293.281405ms)","trace[257789233] 'applied index is now lower than readState.Index' (duration: 230.285µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:43:42.713687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.713834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:43:42.713761Z","caller":"traceutil/trace.go:171","msg":"trace[845904462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"293.832746ms","start":"2026-05-27T19:43:42.419909Z","end":"2026-05-27T19:43:42.713741Z","steps":["trace[845904462] 'agreement among raft nodes before linearized reading' (duration: 293.703633ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:42.713592Z","caller":"traceutil/trace.go:171","msg":"trace[1477877866] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"410.560997ms","start":"2026-05-27T19:43:42.302974Z","end":"2026-05-27T19:43:42.713535Z","steps":["trace[1477877866] 'process raft request' (duration: 410.298792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:42.715604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:42.302953Z","time spent":"411.372273ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:45:16.913503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.308436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-91e14e92-6af9-44bc-8003-d25faa24e003\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-27T19:45:16.913569Z","caller":"traceutil/trace.go:171","msg":"trace[333344975] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-91e14e92-6af9-44bc-8003-d25faa24e003; range_end:; response_count:1; response_revision:1874; }","duration":"144.450009ms","start":"2026-05-27T19:45:16.769105Z","end":"2026-05-27T19:45:16.913555Z","steps":["trace[333344975] 'range keys from in-memory index tree' (duration: 144.167933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:16.913552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.215994ms","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-27T19:45:16.913627Z","caller":"traceutil/trace.go:171","msg":"trace[1758688982] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1874; }","duration":"135.326397ms","start":"2026-05-27T19:45:16.778282Z","end":"2026-05-27T19:45:16.913608Z","steps":["trace[1758688982] 'range keys from in-memory index tree' (duration: 135.029761ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:16.946143Z","caller":"traceutil/trace.go:171","msg":"trace[555376263] linearizableReadLoop","detail":"{readStateIndex:1947; appliedIndex:1946; }","duration":"162.891016ms","start":"2026-05-27T19:45:16.783203Z","end":"2026-05-27T19:45:16.946094Z","steps":["trace[555376263] 'read index received' (duration: 107.953938ms)","trace[555376263] 'applied index is now lower than readState.Index' (duration: 54.935278ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:45:16.94634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.120751ms","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-27T19:45:16.946405Z","caller":"traceutil/trace.go:171","msg":"trace[1901875861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1874; }","duration":"163.229413ms","start":"2026-05-27T19:45:16.783166Z","end":"2026-05-27T19:45:16.946396Z","steps":["trace[1901875861] 'agreement among raft nodes before linearized reading' (duration: 162.995649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:16.978435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.594898ms","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-27T19:45:16.9785Z","caller":"traceutil/trace.go:171","msg":"trace[683751767] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1877; }","duration":"138.701011ms","start":"2026-05-27T19:45:16.839783Z","end":"2026-05-27T19:45:16.978484Z","steps":["trace[683751767] 'agreement among raft nodes before linearized reading' (duration: 138.584758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:06.890165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.698506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:46:06.890255Z","caller":"traceutil/trace.go:171","msg":"trace[685030902] linearizableReadLoop","detail":"{readStateIndex:2276; appliedIndex:2274; }","duration":"259.574277ms","start":"2026-05-27T19:46:06.630665Z","end":"2026-05-27T19:46:06.890239Z","steps":["trace[685030902] 'read index received' (duration: 3.565058ms)","trace[685030902] 'applied index is now lower than readState.Index' (duration: 256.008229ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:06.890342Z","caller":"traceutil/trace.go:171","msg":"trace[250221922] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"267.1802ms","start":"2026-05-27T19:46:06.623121Z","end":"2026-05-27T19:46:06.890301Z","steps":["trace[250221922] 'process raft request' (duration: 151.166087ms)","trace[250221922] 'compare' (duration: 115.571974ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:46:06.890502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.839672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:46:06.890529Z","caller":"traceutil/trace.go:171","msg":"trace[602850269] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2192; }","duration":"259.901662ms","start":"2026-05-27T19:46:06.630619Z","end":"2026-05-27T19:46:06.890521Z","steps":["trace[602850269] 'agreement among raft nodes before linearized reading' (duration: 259.675718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:06.93896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.814779ms","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-27T19:46:06.939017Z","caller":"traceutil/trace.go:171","msg":"trace[1340263715] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2193; }","duration":"133.934352ms","start":"2026-05-27T19:46:06.805069Z","end":"2026-05-27T19:46:06.939003Z","steps":["trace[1340263715] 'agreement among raft nodes before linearized reading' (duration: 133.713478ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:47.264121Z","caller":"traceutil/trace.go:171","msg":"trace[1956185277] transaction","detail":"{read_only:false; response_revision:2586; number_of_response:1; }","duration":"111.144031ms","start":"2026-05-27T19:46:47.152942Z","end":"2026-05-27T19:46:47.264086Z","steps":["trace[1956185277] 'process raft request' (duration: 15.782548ms)","trace[1956185277] 'compare' (duration: 95.094258ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:49:56.100602Z","caller":"traceutil/trace.go:171","msg":"trace[1417354225] transaction","detail":"{read_only:false; response_revision:3805; number_of_response:1; }","duration":"129.069327ms","start":"2026-05-27T19:49:55.971496Z","end":"2026-05-27T19:49:56.100566Z","steps":["trace[1417354225] 'process raft request' (duration: 111.748316ms)","trace[1417354225] 'compare' (duration: 17.101977ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:51:18.910047Z","caller":"traceutil/trace.go:171","msg":"trace[806401018] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"134.209636ms","start":"2026-05-27T19:51:18.77581Z","end":"2026-05-27T19:51:18.910019Z","steps":["trace[806401018] 'process raft request' (duration: 133.994492ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:51:24.617949Z","caller":"traceutil/trace.go:171","msg":"trace[1228955241] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"100.632937ms","start":"2026-05-27T19:51:24.51729Z","end":"2026-05-27T19:51:24.617923Z","steps":["trace[1228955241] 'process raft request' (duration: 100.487713ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:51:24.736982Z","caller":"traceutil/trace.go:171","msg":"trace[170972263] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"110.218608ms","start":"2026-05-27T19:51:24.626731Z","end":"2026-05-27T19:51:24.73695Z","steps":["trace[170972263] 'process raft request' (duration: 100.365081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:51:35.808239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.029267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:51:35.80862Z","caller":"traceutil/trace.go:171","msg":"trace[839213153] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"127.661149ms","start":"2026-05-27T19:51:35.680923Z","end":"2026-05-27T19:51:35.808584Z","steps":["trace[839213153] 'process raft request' (duration: 20.219434ms)","trace[839213153] 'compare' (duration: 106.877844ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:51:36.026471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.488576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:51:36.026673Z","caller":"traceutil/trace.go:171","msg":"trace[723560476] linearizableReadLoop","detail":"{readStateIndex:5463; appliedIndex:5460; }","duration":"116.557168ms","start":"2026-05-27T19:51:35.910103Z","end":"2026-05-27T19:51:36.02666Z","steps":["trace[723560476] 'read index received' (duration: 8.801727ms)","trace[723560476] 'applied index is now lower than readState.Index' (duration: 107.754681ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:51:36.026762Z","caller":"traceutil/trace.go:171","msg":"trace[1838015595] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"160.833112ms","start":"2026-05-27T19:51:35.865915Z","end":"2026-05-27T19:51:36.026748Z","steps":["trace[1838015595] 'process raft request' (duration: 52.999349ms)","trace[1838015595] 'compare' (duration: 107.323763ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:51:36.026799Z","caller":"traceutil/trace.go:171","msg":"trace[1197208963] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"160.773202ms","start":"2026-05-27T19:51:35.866003Z","end":"2026-05-27T19:51:36.026777Z","steps":["trace[1197208963] 'process raft request' (duration: 160.549987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:51:36.027091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.977797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:51:36.027185Z","caller":"traceutil/trace.go:171","msg":"trace[908987313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5263; }","duration":"117.11251ms","start":"2026-05-27T19:51:35.910049Z","end":"2026-05-27T19:51:36.027161Z","steps":["trace[908987313] 'agreement among raft nodes before linearized reading' (duration: 116.953137ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:51:36.026812Z","caller":"traceutil/trace.go:171","msg":"trace[986539705] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"160.011566ms","start":"2026-05-27T19:51:35.866763Z","end":"2026-05-27T19:51:36.026775Z","steps":["trace[986539705] 'process raft request' (duration: 159.857094ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:52:58.444788Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2998} {"level":"info","ts":"2026-05-27T19:52:58.513883Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2998,"took":"67.182276ms","hash":3030388539,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":33923072,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-27T19:52:58.513969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3030388539,"revision":2998,"compact-revision":-1} {"level":"info","ts":"2026-05-27T19:57:48.401835Z","caller":"traceutil/trace.go:171","msg":"trace[1117068658] linearizableReadLoop","detail":"{readStateIndex:8533; appliedIndex:8532; }","duration":"115.669824ms","start":"2026-05-27T19:57:48.286139Z","end":"2026-05-27T19:57:48.401809Z","steps":["trace[1117068658] 'read index received' (duration: 115.439049ms)","trace[1117068658] 'applied index is now lower than readState.Index' (duration: 229.775µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:57:48.402053Z","caller":"traceutil/trace.go:171","msg":"trace[527868215] transaction","detail":"{read_only:false; response_revision:8243; number_of_response:1; }","duration":"227.472226ms","start":"2026-05-27T19:57:48.174567Z","end":"2026-05-27T19:57:48.402039Z","steps":["trace[527868215] 'process raft request' (duration: 227.087628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:57:48.402087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.932099ms","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-27T19:57:48.402162Z","caller":"traceutil/trace.go:171","msg":"trace[1490630881] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8243; }","duration":"116.051532ms","start":"2026-05-27T19:57:48.286093Z","end":"2026-05-27T19:57:48.402144Z","steps":["trace[1490630881] 'agreement among raft nodes before linearized reading' (duration: 115.831347ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:57:48.541075Z","caller":"traceutil/trace.go:171","msg":"trace[112688975] linearizableReadLoop","detail":"{readStateIndex:8534; appliedIndex:8533; }","duration":"119.590248ms","start":"2026-05-27T19:57:48.421457Z","end":"2026-05-27T19:57:48.541047Z","steps":["trace[112688975] 'read index received' (duration: 76.373068ms)","trace[112688975] 'applied index is now lower than readState.Index' (duration: 43.21578ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:57:48.541206Z","caller":"traceutil/trace.go:171","msg":"trace[835082611] transaction","detail":"{read_only:false; response_revision:8244; number_of_response:1; }","duration":"135.057212ms","start":"2026-05-27T19:57:48.406097Z","end":"2026-05-27T19:57:48.541154Z","steps":["trace[835082611] 'process raft request' (duration: 91.842162ms)","trace[835082611] 'compare' (duration: 42.958945ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:57:48.541376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.888243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hm8wb\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-27T19:57:48.541511Z","caller":"traceutil/trace.go:171","msg":"trace[1982175629] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hm8wb; range_end:; response_count:1; response_revision:8244; }","duration":"120.037606ms","start":"2026-05-27T19:57:48.421412Z","end":"2026-05-27T19:57:48.541449Z","steps":["trace[1982175629] 'agreement among raft nodes before linearized reading' (duration: 119.754031ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:57:58.451326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6101} {"level":"info","ts":"2026-05-27T19:57:58.549287Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6101,"took":"95.265707ms","hash":2959314429,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":23826432,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-27T19:57:58.549357Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2959314429,"revision":6101,"compact-revision":2998} {"level":"info","ts":"2026-05-27T19:58:38.228522Z","caller":"traceutil/trace.go:171","msg":"trace[658177930] transaction","detail":"{read_only:false; response_revision:8596; number_of_response:1; }","duration":"145.985698ms","start":"2026-05-27T19:58:38.08249Z","end":"2026-05-27T19:58:38.228475Z","steps":["trace[658177930] 'process raft request' (duration: 145.749384ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:58:48.766551Z","caller":"traceutil/trace.go:171","msg":"trace[634754822] transaction","detail":"{read_only:false; response_revision:8650; number_of_response:1; }","duration":"101.991997ms","start":"2026-05-27T19:58:48.664537Z","end":"2026-05-27T19:58:48.766529Z","steps":["trace[634754822] 'process raft request' (duration: 101.800013ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:58:49.077221Z","caller":"traceutil/trace.go:171","msg":"trace[541390495] linearizableReadLoop","detail":"{readStateIndex:8960; appliedIndex:8959; }","duration":"161.511016ms","start":"2026-05-27T19:58:48.915685Z","end":"2026-05-27T19:58:49.077196Z","steps":["trace[541390495] 'read index received' (duration: 151.549237ms)","trace[541390495] 'applied index is now lower than readState.Index' (duration: 9.960729ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:58:49.077258Z","caller":"traceutil/trace.go:171","msg":"trace[458248081] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"269.21136ms","start":"2026-05-27T19:58:48.808002Z","end":"2026-05-27T19:58:49.077214Z","steps":["trace[458248081] 'process raft request' (duration: 259.286291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:58:49.077355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.6988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:58:49.07741Z","caller":"traceutil/trace.go:171","msg":"trace[1699306910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8651; }","duration":"161.767911ms","start":"2026-05-27T19:58:48.915627Z","end":"2026-05-27T19:58:49.077395Z","steps":["trace[1699306910] 'agreement among raft nodes before linearized reading' (duration: 161.653759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:58:49.077471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.139622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hm8wb\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-27T19:58:49.077573Z","caller":"traceutil/trace.go:171","msg":"trace[244456753] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hm8wb; range_end:; response_count:1; response_revision:8651; }","duration":"157.273555ms","start":"2026-05-27T19:58:48.92028Z","end":"2026-05-27T19:58:49.077554Z","steps":["trace[244456753] 'agreement among raft nodes before linearized reading' (duration: 157.056391ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:59:09.308506Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002416.wal"} {"level":"info","ts":"2026-05-27T20:00:36.522149Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6e120e57916b9bd4","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-27T20:00:36.528754Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-27T20:00:36.528844Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-27T20:02:58.458061Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8298} {"level":"info","ts":"2026-05-27T20:02:58.543187Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8298,"took":"81.523559ms","hash":1110784802,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":25628672,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-27T20:02:58.543277Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1110784802,"revision":8298,"compact-revision":6101} {"level":"warn","ts":"2026-05-27T20:03:41.758712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.080004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.176\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-27T20:03:41.758782Z","caller":"traceutil/trace.go:171","msg":"trace[763049249] range","detail":"{range_begin:/registry/masterleases/199.19.213.176; range_end:; response_count:1; response_revision:11227; }","duration":"171.190126ms","start":"2026-05-27T20:03:41.587576Z","end":"2026-05-27T20:03:41.758766Z","steps":["trace[763049249] 'range keys from in-memory index tree' (duration: 170.86583ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:07:58.464213Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10786} {"level":"info","ts":"2026-05-27T20:07:58.538123Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10786,"took":"71.43702ms","hash":516138396,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":27922432,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-27T20:07:58.538182Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":516138396,"revision":10786,"compact-revision":8298} {"level":"info","ts":"2026-05-27T20:09:26.67445Z","caller":"traceutil/trace.go:171","msg":"trace[241635213] linearizableReadLoop","detail":"{readStateIndex:14084; appliedIndex:14083; }","duration":"116.07883ms","start":"2026-05-27T20:09:26.558354Z","end":"2026-05-27T20:09:26.674433Z","steps":["trace[241635213] 'read index received' (duration: 115.894637ms)","trace[241635213] 'applied index is now lower than readState.Index' (duration: 176.043µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:09:26.674629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.245543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T20:09:26.674601Z","caller":"traceutil/trace.go:171","msg":"trace[144895080] transaction","detail":"{read_only:false; response_revision:13621; number_of_response:1; }","duration":"131.364141ms","start":"2026-05-27T20:09:26.543182Z","end":"2026-05-27T20:09:26.674546Z","steps":["trace[144895080] 'process raft request' (duration: 131.125497ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:09:26.674698Z","caller":"traceutil/trace.go:171","msg":"trace[1740610155] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:13621; }","duration":"116.356075ms","start":"2026-05-27T20:09:26.558328Z","end":"2026-05-27T20:09:26.674684Z","steps":["trace[1740610155] 'agreement among raft nodes before linearized reading' (duration: 116.220362ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:10:06.216044Z","caller":"traceutil/trace.go:171","msg":"trace[1049191490] transaction","detail":"{read_only:false; response_revision:13849; number_of_response:1; }","duration":"263.021699ms","start":"2026-05-27T20:10:05.952999Z","end":"2026-05-27T20:10:06.216021Z","steps":["trace[1049191490] 'process raft request' (duration: 262.819525ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:10:06.237758Z","caller":"traceutil/trace.go:171","msg":"trace[222751549] linearizableReadLoop","detail":"{readStateIndex:14322; appliedIndex:14320; }","duration":"245.018496ms","start":"2026-05-27T20:10:05.992714Z","end":"2026-05-27T20:10:06.237733Z","steps":["trace[222751549] 'read index received' (duration: 223.16397ms)","trace[222751549] 'applied index is now lower than readState.Index' (duration: 21.853096ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:10:06.237955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.23556ms","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-27T20:10:06.238001Z","caller":"traceutil/trace.go:171","msg":"trace[1445763907] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13850; }","duration":"245.338152ms","start":"2026-05-27T20:10:05.992652Z","end":"2026-05-27T20:10:06.237991Z","steps":["trace[1445763907] 'agreement among raft nodes before linearized reading' (duration: 245.179909ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:10:06.238031Z","caller":"traceutil/trace.go:171","msg":"trace[1477816924] transaction","detail":"{read_only:false; response_revision:13850; number_of_response:1; }","duration":"269.630674ms","start":"2026-05-27T20:10:05.968371Z","end":"2026-05-27T20:10:06.238002Z","steps":["trace[1477816924] 'process raft request' (duration: 269.216717ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:10:15.283771Z","caller":"traceutil/trace.go:171","msg":"trace[368761239] transaction","detail":"{read_only:false; response_revision:14018; number_of_response:1; }","duration":"133.245178ms","start":"2026-05-27T20:10:15.150504Z","end":"2026-05-27T20:10:15.283749Z","steps":["trace[368761239] 'process raft request' (duration: 133.051824ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:10:15.522966Z","caller":"traceutil/trace.go:171","msg":"trace[1611543807] transaction","detail":"{read_only:false; response_revision:14019; number_of_response:1; }","duration":"223.493957ms","start":"2026-05-27T20:10:15.299444Z","end":"2026-05-27T20:10:15.522938Z","steps":["trace[1611543807] 'process raft request' (duration: 223.235382ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:10:15.560122Z","caller":"traceutil/trace.go:171","msg":"trace[878457312] transaction","detail":"{read_only:false; response_revision:14020; number_of_response:1; }","duration":"193.358092ms","start":"2026-05-27T20:10:15.366736Z","end":"2026-05-27T20:10:15.560094Z","steps":["trace[878457312] 'process raft request' (duration: 193.21866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:11:54.840131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.308812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-05-27T20:11:54.840226Z","caller":"traceutil/trace.go:171","msg":"trace[653689476] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15101; }","duration":"116.468935ms","start":"2026-05-27T20:11:54.723736Z","end":"2026-05-27T20:11:54.840205Z","steps":["trace[653689476] 'range keys from in-memory index tree' (duration: 116.097988ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:12:58.471349Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13006} {"level":"info","ts":"2026-05-27T20:12:58.550607Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13006,"took":"76.058167ms","hash":1807802793,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":31457280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-27T20:12:58.550718Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1807802793,"revision":13006,"compact-revision":10786} {"level":"warn","ts":"2026-05-27T20:16:04.167326Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228773953175093744,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T20:16:04.672551Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228773953175093744,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T20:16:05.1745Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228773953175093744,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T20:16:05.67518Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228773953175093744,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T20:16:05.910246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000223131s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-27T20:16:05.910316Z","caller":"traceutil/trace.go:171","msg":"trace[578110770] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000340153s","start":"2026-05-27T20:16:03.90996Z","end":"2026-05-27T20:16:05.9103Z","steps":["trace[578110770] 'agreement among raft nodes before linearized reading' (duration: 2.000257261s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:05.910347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:03.909937Z","time spent":"2.000404795s","remote":"127.0.0.1:50794","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-27T20:16:06.17606Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11228773953175093744,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T20:16:06.622837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.347497316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fjkst\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-05-27T20:16:06.622907Z","caller":"traceutil/trace.go:171","msg":"trace[1367316570] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fjkst; range_end:; response_count:1; response_revision:16977; }","duration":"3.347613608s","start":"2026-05-27T20:16:03.275276Z","end":"2026-05-27T20:16:06.62289Z","steps":["trace[1367316570] 'range keys from in-memory index tree' (duration: 3.347292083s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.622947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:03.275254Z","time spent":"3.3476825s","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22149,"request content":"key:\"/registry/pods/openstack/nova-compute-default-fjkst\" "} {"level":"warn","ts":"2026-05-27T20:16:06.623243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.342149445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fjkst\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-05-27T20:16:06.623279Z","caller":"traceutil/trace.go:171","msg":"trace[1588016222] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fjkst; range_end:; response_count:1; response_revision:16977; }","duration":"3.342215586s","start":"2026-05-27T20:16:03.281053Z","end":"2026-05-27T20:16:06.623269Z","steps":["trace[1588016222] 'range keys from in-memory index tree' (duration: 3.342015683s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.623307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:03.281036Z","time spent":"3.342264797s","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22149,"request content":"key:\"/registry/pods/openstack/nova-compute-default-fjkst\" "} {"level":"warn","ts":"2026-05-27T20:16:06.623507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:03.292919Z","time spent":"3.330585216s","remote":"127.0.0.1:42710","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-27T20:16:06.623937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.306212993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-27T20:16:06.623984Z","caller":"traceutil/trace.go:171","msg":"trace[1642780251] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16977; }","duration":"3.306282453s","start":"2026-05-27T20:16:03.31769Z","end":"2026-05-27T20:16:06.623972Z","steps":["trace[1642780251] 'range keys from in-memory index tree' (duration: 3.306051409s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.624015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:03.317674Z","time spent":"3.306331814s","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-27T20:16:06.624306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.293737856s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:16:06.624361Z","caller":"traceutil/trace.go:171","msg":"trace[1561013360] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:0; response_revision:16977; }","duration":"3.293817557s","start":"2026-05-27T20:16:03.330527Z","end":"2026-05-27T20:16:06.624344Z","steps":["trace[1561013360] 'range keys from in-memory index tree' (duration: 3.293681835s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.624422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:03.330512Z","time spent":"3.293895749s","remote":"127.0.0.1:51008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/neutron-ks-user\" "} {"level":"warn","ts":"2026-05-27T20:16:06.624696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.293297727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" ","response":"range_response_count:1 size:12633"} {"level":"info","ts":"2026-05-27T20:16:06.624741Z","caller":"traceutil/trace.go:171","msg":"trace[1248820774] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-endpoints; range_end:; response_count:1; response_revision:16977; }","duration":"3.293360008s","start":"2026-05-27T20:16:03.331368Z","end":"2026-05-27T20:16:06.624728Z","steps":["trace[1248820774] 'range keys from in-memory index tree' (duration: 3.293200885s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.624779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:03.331356Z","time spent":"3.293412739s","remote":"127.0.0.1:51008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12656,"request content":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" "} {"level":"warn","ts":"2026-05-27T20:16:06.624865Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.06853633s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T20:16:06.627805Z","caller":"traceutil/trace.go:171","msg":"trace[1810431360] transaction","detail":"{read_only:false; response_revision:16978; number_of_response:1; }","duration":"3.071631108s","start":"2026-05-27T20:16:03.556142Z","end":"2026-05-27T20:16:06.627773Z","steps":["trace[1810431360] 'process raft request' (duration: 3.069484148s)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:16:06.627844Z","caller":"traceutil/trace.go:171","msg":"trace[1074648286] transaction","detail":"{read_only:false; response_revision:16979; number_of_response:1; }","duration":"2.552015705s","start":"2026-05-27T20:16:04.075801Z","end":"2026-05-27T20:16:06.627817Z","steps":["trace[1074648286] 'process raft request' (duration: 2.551879572s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.627997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:03.556119Z","time spent":"3.071789862s","remote":"127.0.0.1:51044","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-27T20:16:06.628038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.961085171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-27T20:16:06.628053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:04.07578Z","time spent":"2.552195068s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:16:06.628078Z","caller":"traceutil/trace.go:171","msg":"trace[920981223] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:16981; }","duration":"2.961171063s","start":"2026-05-27T20:16:03.666896Z","end":"2026-05-27T20:16:06.628067Z","steps":["trace[920981223] 'agreement among raft nodes before linearized reading' (duration: 2.961067261s)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:16:06.627932Z","caller":"traceutil/trace.go:171","msg":"trace[2081819198] linearizableReadLoop","detail":"{readStateIndex:17550; appliedIndex:17546; }","duration":"2.960913117s","start":"2026-05-27T20:16:03.666929Z","end":"2026-05-27T20:16:06.627842Z","steps":["trace[2081819198] 'read index received' (duration: 2.959737676s)","trace[2081819198] 'applied index is now lower than readState.Index' (duration: 1.174251ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:16:06.628109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:03.666881Z","time spent":"2.961218583s","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-05-27T20:16:06.62818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.907588464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:16:06.628213Z","caller":"traceutil/trace.go:171","msg":"trace[1444235521] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:16981; }","duration":"2.907658165s","start":"2026-05-27T20:16:03.720543Z","end":"2026-05-27T20:16:06.628201Z","steps":["trace[1444235521] 'agreement among raft nodes before linearized reading' (duration: 2.907600224s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.628237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:03.720519Z","time spent":"2.907711548s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true "} {"level":"info","ts":"2026-05-27T20:16:06.628189Z","caller":"traceutil/trace.go:171","msg":"trace[1975567854] transaction","detail":"{read_only:false; response_revision:16981; number_of_response:1; }","duration":"1.16686485s","start":"2026-05-27T20:16:05.46128Z","end":"2026-05-27T20:16:06.628145Z","steps":["trace[1975567854] 'process raft request' (duration: 1.166522784s)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:16:06.62818Z","caller":"traceutil/trace.go:171","msg":"trace[439769631] transaction","detail":"{read_only:false; response_revision:16980; number_of_response:1; }","duration":"1.541722356s","start":"2026-05-27T20:16:05.086406Z","end":"2026-05-27T20:16:06.628128Z","steps":["trace[439769631] 'process raft request' (duration: 1.541352799s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.628257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.955421591s","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-27T20:16:06.628299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.582663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:16:06.628311Z","caller":"traceutil/trace.go:171","msg":"trace[1291513015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16981; }","duration":"1.955509702s","start":"2026-05-27T20:16:04.672785Z","end":"2026-05-27T20:16:06.628294Z","steps":["trace[1291513015] 'agreement among raft nodes before linearized reading' (duration: 1.955362238s)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:16:06.628328Z","caller":"traceutil/trace.go:171","msg":"trace[1565907899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16981; }","duration":"717.631823ms","start":"2026-05-27T20:16:05.910688Z","end":"2026-05-27T20:16:06.628319Z","steps":["trace[1565907899] 'agreement among raft nodes before linearized reading' (duration: 717.589453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.628344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:04.672765Z","time spent":"1.955570624s","remote":"127.0.0.1:51044","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-27T20:16:06.628353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:05.910676Z","time spent":"717.671304ms","remote":"127.0.0.1:50792","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-27T20:16:06.628371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.955544153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-27T20:16:06.628366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:05.461258Z","time spent":"1.167017384s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:16:06.62842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.520987236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-27T20:16:06.628413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:05.086389Z","time spent":"1.5419093s","remote":"127.0.0.1:51044","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-27T20:16:06.62844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.454151907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-g6p6w\" ","response":"range_response_count:1 size:9800"} {"level":"warn","ts":"2026-05-27T20:16:06.628457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.115374208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-27T20:16:06.628477Z","caller":"traceutil/trace.go:171","msg":"trace[1164861774] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-g6p6w; range_end:; response_count:1; response_revision:16981; }","duration":"2.454210908s","start":"2026-05-27T20:16:04.174257Z","end":"2026-05-27T20:16:06.628468Z","steps":["trace[1164861774] 'agreement among raft nodes before linearized reading' (duration: 2.454110756s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.628496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.64863994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hm8wb\" ","response":"range_response_count:1 size:6525"} {"level":"info","ts":"2026-05-27T20:16:06.628495Z","caller":"traceutil/trace.go:171","msg":"trace[639370505] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16981; }","duration":"2.115413918s","start":"2026-05-27T20:16:04.51307Z","end":"2026-05-27T20:16:06.628484Z","steps":["trace[639370505] 'agreement among raft nodes before linearized reading' (duration: 2.115300766s)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:16:06.628513Z","caller":"traceutil/trace.go:171","msg":"trace[2079970040] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hm8wb; range_end:; response_count:1; response_revision:16981; }","duration":"2.648676371s","start":"2026-05-27T20:16:03.979831Z","end":"2026-05-27T20:16:06.628507Z","steps":["trace[2079970040] 'agreement among raft nodes before linearized reading' (duration: 2.64862s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.628533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:03.979815Z","time spent":"2.648713521s","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-hm8wb\" "} {"level":"warn","ts":"2026-05-27T20:16:06.628533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:04.513018Z","time spent":"2.11550686s","remote":"127.0.0.1:50930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-05-27T20:16:06.62839Z","caller":"traceutil/trace.go:171","msg":"trace[1961200923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16981; }","duration":"1.955587714s","start":"2026-05-27T20:16:04.672797Z","end":"2026-05-27T20:16:06.628384Z","steps":["trace[1961200923] 'agreement among raft nodes before linearized reading' (duration: 1.955526712s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.628537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.600949141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-05-27T20:16:06.628561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.323531739s","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":"warn","ts":"2026-05-27T20:16:06.628571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:04.672776Z","time spent":"1.955786018s","remote":"127.0.0.1:51044","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-27T20:16:06.628575Z","caller":"traceutil/trace.go:171","msg":"trace[1259761751] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16981; }","duration":"1.601021913s","start":"2026-05-27T20:16:05.027543Z","end":"2026-05-27T20:16:06.628565Z","steps":["trace[1259761751] 'agreement among raft nodes before linearized reading' (duration: 1.600922591s)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:16:06.628586Z","caller":"traceutil/trace.go:171","msg":"trace[1777814800] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:16981; }","duration":"2.32362149s","start":"2026-05-27T20:16:04.304955Z","end":"2026-05-27T20:16:06.628577Z","steps":["trace[1777814800] 'agreement among raft nodes before linearized reading' (duration: 2.323559879s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.62861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:04.304933Z","time spent":"2.323671131s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-05-27T20:16:06.628609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:05.027525Z","time spent":"1.601075954s","remote":"127.0.0.1:50930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T20:16:06.62825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.551199147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-05-27T20:16:06.628505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050776367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:16:06.628655Z","caller":"traceutil/trace.go:171","msg":"trace[1622400302] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16981; }","duration":"1.551630964s","start":"2026-05-27T20:16:05.077008Z","end":"2026-05-27T20:16:06.628638Z","steps":["trace[1622400302] 'agreement among raft nodes before linearized reading' (duration: 1.551150854s)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:16:06.628672Z","caller":"traceutil/trace.go:171","msg":"trace[850228253] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:16981; }","duration":"1.050970361s","start":"2026-05-27T20:16:05.577693Z","end":"2026-05-27T20:16:06.628663Z","steps":["trace[850228253] 'agreement among raft nodes before linearized reading' (duration: 1.050787537s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.628681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.810997862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-vpn-agent-default-mhwqd\" ","response":"range_response_count:1 size:15445"} {"level":"warn","ts":"2026-05-27T20:16:06.628688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:05.07699Z","time spent":"1.551687856s","remote":"127.0.0.1:50930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T20:16:06.6287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.327844709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-77c6b97f97-cdc5b.18b3846902fd158b\" ","response":"range_response_count:1 size:1136"} {"level":"warn","ts":"2026-05-27T20:16:06.6287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:05.577676Z","time spent":"1.051016072s","remote":"127.0.0.1:40552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-05-27T20:16:06.628722Z","caller":"traceutil/trace.go:171","msg":"trace[514675079] range","detail":"{range_begin:/registry/events/openstack/barbican-api-77c6b97f97-cdc5b.18b3846902fd158b; range_end:; response_count:1; response_revision:16981; }","duration":"2.327902511s","start":"2026-05-27T20:16:04.300813Z","end":"2026-05-27T20:16:06.628716Z","steps":["trace[514675079] 'agreement among raft nodes before linearized reading' (duration: 2.32783995s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.62872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.038336935s","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":"warn","ts":"2026-05-27T20:16:06.62874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:04.300789Z","time spent":"2.327946041s","remote":"127.0.0.1:50854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1159,"request content":"key:\"/registry/events/openstack/barbican-api-77c6b97f97-cdc5b.18b3846902fd158b\" "} {"level":"info","ts":"2026-05-27T20:16:06.628756Z","caller":"traceutil/trace.go:171","msg":"trace[135097212] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16981; }","duration":"2.038399315s","start":"2026-05-27T20:16:04.590345Z","end":"2026-05-27T20:16:06.628744Z","steps":["trace[135097212] 'agreement among raft nodes before linearized reading' (duration: 2.038301383s)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:16:06.62844Z","caller":"traceutil/trace.go:171","msg":"trace[1277142978] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:16981; }","duration":"2.521041637s","start":"2026-05-27T20:16:04.107391Z","end":"2026-05-27T20:16:06.628433Z","steps":["trace[1277142978] 'agreement among raft nodes before linearized reading' (duration: 2.521010177s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.628784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:04.590322Z","time spent":"2.038454357s","remote":"127.0.0.1:51044","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-27T20:16:06.62876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.905143908s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-metadata-agent-default-7v2nd\" ","response":"range_response_count:1 size:15436"} {"level":"warn","ts":"2026-05-27T20:16:06.628797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.654369075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-27T20:16:06.62881Z","caller":"traceutil/trace.go:171","msg":"trace[1758517351] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-metadata-agent-default-7v2nd; range_end:; response_count:1; response_revision:16981; }","duration":"2.90521131s","start":"2026-05-27T20:16:03.723593Z","end":"2026-05-27T20:16:06.628805Z","steps":["trace[1758517351] 'agreement among raft nodes before linearized reading' (duration: 2.905122089s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.628566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.354432731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-27T20:16:06.628831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:03.723583Z","time spent":"2.905243591s","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":15459,"request content":"key:\"/registry/pods/openstack/neutron-ovn-metadata-agent-default-7v2nd\" "} {"level":"info","ts":"2026-05-27T20:16:06.628833Z","caller":"traceutil/trace.go:171","msg":"trace[1359291465] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:16981; }","duration":"1.654419716s","start":"2026-05-27T20:16:04.9744Z","end":"2026-05-27T20:16:06.628819Z","steps":["trace[1359291465] 'agreement among raft nodes before linearized reading' (duration: 1.654330924s)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:16:06.628847Z","caller":"traceutil/trace.go:171","msg":"trace[1538523014] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:16981; }","duration":"1.354745697s","start":"2026-05-27T20:16:05.274092Z","end":"2026-05-27T20:16:06.628838Z","steps":["trace[1538523014] 'agreement among raft nodes before linearized reading' (duration: 1.354455712s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.62886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:04.974379Z","time spent":"1.654475207s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-27T20:16:06.628872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:05.274072Z","time spent":"1.354792168s","remote":"127.0.0.1:50576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"info","ts":"2026-05-27T20:16:06.628701Z","caller":"traceutil/trace.go:171","msg":"trace[1409008891] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-vpn-agent-default-mhwqd; range_end:; response_count:1; response_revision:16981; }","duration":"2.811063293s","start":"2026-05-27T20:16:03.817631Z","end":"2026-05-27T20:16:06.628695Z","steps":["trace[1409008891] 'agreement among raft nodes before linearized reading' (duration: 2.810992742s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.628895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:03.817609Z","time spent":"2.811279768s","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":15468,"request content":"key:\"/registry/pods/openstack/neutron-ovn-vpn-agent-default-mhwqd\" "} {"level":"warn","ts":"2026-05-27T20:16:06.628501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:04.174245Z","time spent":"2.454251129s","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9823,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-g6p6w\" "} {"level":"warn","ts":"2026-05-27T20:16:06.628967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.77002841s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-27T20:16:06.628971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.054830387s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-27T20:16:06.628805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:04.107375Z","time spent":"2.521415034s","remote":"127.0.0.1:35474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"info","ts":"2026-05-27T20:16:06.629003Z","caller":"traceutil/trace.go:171","msg":"trace[507607317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16981; }","duration":"2.054903779s","start":"2026-05-27T20:16:04.574091Z","end":"2026-05-27T20:16:06.628994Z","steps":["trace[507607317] 'agreement among raft nodes before linearized reading' (duration: 2.054819697s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.629028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:04.574064Z","time spent":"2.054957179s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T20:16:06.629067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.946393578s","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-27T20:16:06.628997Z","caller":"traceutil/trace.go:171","msg":"trace[1217632193] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:16981; }","duration":"1.770085922s","start":"2026-05-27T20:16:04.858899Z","end":"2026-05-27T20:16:06.628985Z","steps":["trace[1217632193] 'agreement among raft nodes before linearized reading' (duration: 1.770041111s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.628538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252325012s","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:8"} {"level":"info","ts":"2026-05-27T20:16:06.629089Z","caller":"traceutil/trace.go:171","msg":"trace[1683446656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16981; }","duration":"1.94643992s","start":"2026-05-27T20:16:04.682642Z","end":"2026-05-27T20:16:06.629082Z","steps":["trace[1683446656] 'agreement among raft nodes before linearized reading' (duration: 1.946371168s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.629103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:04.858877Z","time spent":"1.770218024s","remote":"127.0.0.1:50544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"warn","ts":"2026-05-27T20:16:06.629109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:04.682629Z","time spent":"1.94647427s","remote":"127.0.0.1:51044","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-27T20:16:06.629152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.888575331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-9kswj\" ","response":"range_response_count:1 size:20764"} {"level":"info","ts":"2026-05-27T20:16:06.629104Z","caller":"traceutil/trace.go:171","msg":"trace[282276175] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:16981; }","duration":"1.252934774s","start":"2026-05-27T20:16:05.376163Z","end":"2026-05-27T20:16:06.629098Z","steps":["trace[282276175] 'agreement among raft nodes before linearized reading' (duration: 1.252355003s)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:16:06.629181Z","caller":"traceutil/trace.go:171","msg":"trace[6605662] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-9kswj; range_end:; response_count:1; response_revision:16981; }","duration":"1.888638651s","start":"2026-05-27T20:16:04.740535Z","end":"2026-05-27T20:16:06.629173Z","steps":["trace[6605662] 'agreement among raft nodes before linearized reading' (duration: 1.888538219s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:16:06.629188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:05.376142Z","time spent":"1.253038406s","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-27T20:16:06.629206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:16:04.740513Z","time spent":"1.888687103s","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20787,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-9kswj\" "} {"level":"info","ts":"2026-05-27T20:17:40.530628Z","caller":"traceutil/trace.go:171","msg":"trace[1418445528] linearizableReadLoop","detail":"{readStateIndex:18302; appliedIndex:18301; }","duration":"157.97626ms","start":"2026-05-27T20:17:40.372631Z","end":"2026-05-27T20:17:40.530607Z","steps":["trace[1418445528] 'read index received' (duration: 157.783366ms)","trace[1418445528] 'applied index is now lower than readState.Index' (duration: 192.014µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:17:40.530751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.109332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:17:40.530777Z","caller":"traceutil/trace.go:171","msg":"trace[1360434563] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:17709; }","duration":"158.192454ms","start":"2026-05-27T20:17:40.372576Z","end":"2026-05-27T20:17:40.530769Z","steps":["trace[1360434563] 'agreement among raft nodes before linearized reading' (duration: 158.122542ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:17:40.530757Z","caller":"traceutil/trace.go:171","msg":"trace[1465602725] transaction","detail":"{read_only:false; response_revision:17709; number_of_response:1; }","duration":"325.832467ms","start":"2026-05-27T20:17:40.204886Z","end":"2026-05-27T20:17:40.530719Z","steps":["trace[1465602725] 'process raft request' (duration: 325.567342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:17:40.531017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:17:40.204864Z","time spent":"326.03695ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20981,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:17:58.477387Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15526} {"level":"info","ts":"2026-05-27T20:17:58.583546Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15526,"took":"102.827693ms","hash":3703766863,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":33230848,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-27T20:17:58.58361Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3703766863,"revision":15526,"compact-revision":13006} {"level":"info","ts":"2026-05-27T20:21:02.208858Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6e120e57916b9bd4","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-27T20:21:02.214719Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-27T20:21:02.214929Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-27T20:22:51.769139Z","caller":"traceutil/trace.go:171","msg":"trace[65989038] linearizableReadLoop","detail":"{readStateIndex:20791; appliedIndex:20790; }","duration":"104.605206ms","start":"2026-05-27T20:22:51.664501Z","end":"2026-05-27T20:22:51.769107Z","steps":["trace[65989038] 'read index received' (duration: 104.361011ms)","trace[65989038] 'applied index is now lower than readState.Index' (duration: 242.284µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:22:51.769292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.764808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:22:51.769343Z","caller":"traceutil/trace.go:171","msg":"trace[1027735458] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:20117; }","duration":"104.84976ms","start":"2026-05-27T20:22:51.664478Z","end":"2026-05-27T20:22:51.769328Z","steps":["trace[1027735458] 'agreement among raft nodes before linearized reading' (duration: 104.751438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:22:57.314605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.737075ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-27T20:22:58.350714Z","caller":"traceutil/trace.go:171","msg":"trace[23324150] transaction","detail":"{read_only:false; response_revision:20149; number_of_response:1; }","duration":"144.238768ms","start":"2026-05-27T20:22:58.206446Z","end":"2026-05-27T20:22:58.350685Z","steps":["trace[23324150] 'process raft request' (duration: 144.082635ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:22:58.473274Z","caller":"traceutil/trace.go:171","msg":"trace[749331237] transaction","detail":"{read_only:false; response_revision:20150; number_of_response:1; }","duration":"247.106859ms","start":"2026-05-27T20:22:58.226127Z","end":"2026-05-27T20:22:58.473234Z","steps":["trace[749331237] 'process raft request' (duration: 246.852895ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:22:58.473273Z","caller":"traceutil/trace.go:171","msg":"trace[91266216] transaction","detail":"{read_only:false; response_revision:20151; number_of_response:1; }","duration":"204.906889ms","start":"2026-05-27T20:22:58.268336Z","end":"2026-05-27T20:22:58.473242Z","steps":["trace[91266216] 'process raft request' (duration: 204.795296ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:22:58.484496Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18045} {"level":"info","ts":"2026-05-27T20:22:58.60054Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18045,"took":"113.344422ms","hash":3324254623,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":30339072,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-27T20:22:58.600609Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3324254623,"revision":18045,"compact-revision":15526} {"level":"warn","ts":"2026-05-27T20:26:46.185094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.47441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:26:46.185192Z","caller":"traceutil/trace.go:171","msg":"trace[1697408595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22553; }","duration":"274.609483ms","start":"2026-05-27T20:26:45.910562Z","end":"2026-05-27T20:26:46.185171Z","steps":["trace[1697408595] 'range keys from in-memory index tree' (duration: 274.409628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:26:46.185558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.40936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:26:46.185678Z","caller":"traceutil/trace.go:171","msg":"trace[2130666518] linearizableReadLoop","detail":"{readStateIndex:23301; appliedIndex:23300; }","duration":"257.668851ms","start":"2026-05-27T20:26:45.92799Z","end":"2026-05-27T20:26:46.185659Z","steps":["trace[2130666518] 'read index received' (duration: 141.927123ms)","trace[2130666518] 'applied index is now lower than readState.Index' (duration: 115.739978ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:26:46.185861Z","caller":"traceutil/trace.go:171","msg":"trace[1957415734] transaction","detail":"{read_only:false; response_revision:22554; number_of_response:1; }","duration":"269.499715ms","start":"2026-05-27T20:26:45.916343Z","end":"2026-05-27T20:26:46.185843Z","steps":["trace[1957415734] 'process raft request' (duration: 153.659327ms)","trace[1957415734] 'compare' (duration: 115.056454ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:26:46.186299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.295882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T20:26:46.18636Z","caller":"traceutil/trace.go:171","msg":"trace[1541842823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22554; }","duration":"258.368704ms","start":"2026-05-27T20:26:45.927975Z","end":"2026-05-27T20:26:46.186344Z","steps":["trace[1541842823] 'agreement among raft nodes before linearized reading' (duration: 258.15319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:26:46.186573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.891551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-27T20:26:46.186628Z","caller":"traceutil/trace.go:171","msg":"trace[677866259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22554; }","duration":"115.000233ms","start":"2026-05-27T20:26:46.071614Z","end":"2026-05-27T20:26:46.186615Z","steps":["trace[677866259] 'agreement among raft nodes before linearized reading' (duration: 114.87298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:26:46.186768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.474873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T20:26:46.186835Z","caller":"traceutil/trace.go:171","msg":"trace[1802985526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22554; }","duration":"114.591235ms","start":"2026-05-27T20:26:46.072228Z","end":"2026-05-27T20:26:46.186819Z","steps":["trace[1802985526] 'agreement among raft nodes before linearized reading' (duration: 114.422092ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:26:46.320114Z","caller":"traceutil/trace.go:171","msg":"trace[1154796359] transaction","detail":"{read_only:false; response_revision:22555; number_of_response:1; }","duration":"129.021459ms","start":"2026-05-27T20:26:46.191063Z","end":"2026-05-27T20:26:46.320085Z","steps":["trace[1154796359] 'process raft request' (duration: 125.983371ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:26:46.326397Z","caller":"traceutil/trace.go:171","msg":"trace[1825645354] transaction","detail":"{read_only:false; response_revision:22558; number_of_response:1; }","duration":"134.932162ms","start":"2026-05-27T20:26:46.191449Z","end":"2026-05-27T20:26:46.326381Z","steps":["trace[1825645354] 'process raft request' (duration: 134.87592ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:26:46.326484Z","caller":"traceutil/trace.go:171","msg":"trace[1658264356] transaction","detail":"{read_only:false; response_revision:22556; number_of_response:1; }","duration":"135.244577ms","start":"2026-05-27T20:26:46.191177Z","end":"2026-05-27T20:26:46.326421Z","steps":["trace[1658264356] 'process raft request' (duration: 135.016163ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:26:46.32651Z","caller":"traceutil/trace.go:171","msg":"trace[2053373894] transaction","detail":"{read_only:false; response_revision:22557; number_of_response:1; }","duration":"135.043343ms","start":"2026-05-27T20:26:46.191432Z","end":"2026-05-27T20:26:46.326475Z","steps":["trace[2053373894] 'process raft request' (duration: 134.8636ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:26:46.326598Z","caller":"traceutil/trace.go:171","msg":"trace[1365912382] transaction","detail":"{read_only:false; response_revision:22559; number_of_response:1; }","duration":"134.228348ms","start":"2026-05-27T20:26:46.192342Z","end":"2026-05-27T20:26:46.32657Z","steps":["trace[1365912382] 'process raft request' (duration: 134.011254ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:27:58.490373Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20152} {"level":"info","ts":"2026-05-27T20:27:58.604875Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20152,"took":"110.395224ms","hash":3984131733,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":41857024,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-27T20:27:58.604957Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3984131733,"revision":20152,"compact-revision":18045} {"level":"info","ts":"2026-05-27T20:29:45.525907Z","caller":"traceutil/trace.go:171","msg":"trace[146872320] transaction","detail":"{read_only:false; response_revision:24419; number_of_response:1; }","duration":"194.633659ms","start":"2026-05-27T20:29:45.33125Z","end":"2026-05-27T20:29:45.525884Z","steps":["trace[146872320] 'process raft request' (duration: 193.68391ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:45.526677Z","caller":"traceutil/trace.go:171","msg":"trace[2076106036] linearizableReadLoop","detail":"{readStateIndex:25214; appliedIndex:25211; }","duration":"190.655492ms","start":"2026-05-27T20:29:45.336004Z","end":"2026-05-27T20:29:45.526659Z","steps":["trace[2076106036] 'read index received' (duration: 44.663188ms)","trace[2076106036] 'applied index is now lower than readState.Index' (duration: 145.991284ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:29:45.52687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.848736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-27T20:29:45.526915Z","caller":"traceutil/trace.go:171","msg":"trace[1067495343] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24420; }","duration":"190.940808ms","start":"2026-05-27T20:29:45.335959Z","end":"2026-05-27T20:29:45.5269Z","steps":["trace[1067495343] 'agreement among raft nodes before linearized reading' (duration: 190.768104ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:45.526969Z","caller":"traceutil/trace.go:171","msg":"trace[363434246] transaction","detail":"{read_only:false; response_revision:24420; number_of_response:1; }","duration":"193.893074ms","start":"2026-05-27T20:29:45.33306Z","end":"2026-05-27T20:29:45.526954Z","steps":["trace[363434246] 'process raft request' (duration: 192.700641ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:46.789424Z","caller":"traceutil/trace.go:171","msg":"trace[625821925] transaction","detail":"{read_only:false; response_revision:24426; number_of_response:1; }","duration":"116.440383ms","start":"2026-05-27T20:29:46.672959Z","end":"2026-05-27T20:29:46.789399Z","steps":["trace[625821925] 'process raft request' (duration: 116.30628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:29:47.057178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.071741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:29:47.057598Z","caller":"traceutil/trace.go:171","msg":"trace[1230684516] transaction","detail":"{read_only:false; response_revision:24429; number_of_response:1; }","duration":"263.778482ms","start":"2026-05-27T20:29:46.793796Z","end":"2026-05-27T20:29:47.057575Z","steps":["trace[1230684516] 'process raft request' (duration: 263.641149ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:47.057711Z","caller":"traceutil/trace.go:171","msg":"trace[39541706] transaction","detail":"{read_only:false; response_revision:24427; number_of_response:1; }","duration":"310.874367ms","start":"2026-05-27T20:29:46.746809Z","end":"2026-05-27T20:29:47.057684Z","steps":["trace[39541706] 'process raft request' (duration: 170.195323ms)","trace[39541706] 'compare' (duration: 139.831807ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:29:47.057745Z","caller":"traceutil/trace.go:171","msg":"trace[1621450829] transaction","detail":"{read_only:false; response_revision:24428; number_of_response:1; }","duration":"264.921534ms","start":"2026-05-27T20:29:46.792801Z","end":"2026-05-27T20:29:47.057723Z","steps":["trace[1621450829] 'process raft request' (duration: 264.514625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:29:47.057858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:29:46.746785Z","time spent":"310.995649ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:29:47.057866Z","caller":"traceutil/trace.go:171","msg":"trace[884393954] transaction","detail":"{read_only:false; response_revision:24430; number_of_response:1; }","duration":"219.320068ms","start":"2026-05-27T20:29:46.838522Z","end":"2026-05-27T20:29:47.057842Z","steps":["trace[884393954] 'process raft request' (duration: 218.996981ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:47.057863Z","caller":"traceutil/trace.go:171","msg":"trace[1663444328] linearizableReadLoop","detail":"{readStateIndex:25224; appliedIndex:25220; }","duration":"147.871959ms","start":"2026-05-27T20:29:46.909953Z","end":"2026-05-27T20:29:47.057825Z","steps":["trace[1663444328] 'read index received' (duration: 7.053853ms)","trace[1663444328] 'applied index is now lower than readState.Index' (duration: 140.814446ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:29:47.058084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.115905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:29:47.058134Z","caller":"traceutil/trace.go:171","msg":"trace[1228622964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24430; }","duration":"148.211566ms","start":"2026-05-27T20:29:46.909908Z","end":"2026-05-27T20:29:47.058119Z","steps":["trace[1228622964] 'agreement among raft nodes before linearized reading' (duration: 148.108604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:29:51.39258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.174744ms","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-27T20:29:51.392851Z","caller":"traceutil/trace.go:171","msg":"trace[531627292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24459; }","duration":"129.48877ms","start":"2026-05-27T20:29:51.263346Z","end":"2026-05-27T20:29:51.392835Z","steps":["trace[531627292] 'range keys from in-memory index tree' (duration: 129.016011ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:51.551622Z","caller":"traceutil/trace.go:171","msg":"trace[387801526] linearizableReadLoop","detail":"{readStateIndex:25255; appliedIndex:25254; }","duration":"148.799667ms","start":"2026-05-27T20:29:51.402805Z","end":"2026-05-27T20:29:51.551604Z","steps":["trace[387801526] 'read index received' (duration: 148.653674ms)","trace[387801526] 'applied index is now lower than readState.Index' (duration: 145.283µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:29:51.551673Z","caller":"traceutil/trace.go:171","msg":"trace[250191866] transaction","detail":"{read_only:false; response_revision:24460; number_of_response:1; }","duration":"154.662778ms","start":"2026-05-27T20:29:51.396978Z","end":"2026-05-27T20:29:51.551641Z","steps":["trace[250191866] 'process raft request' (duration: 154.479265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:29:51.55178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.962131ms","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-27T20:29:51.55181Z","caller":"traceutil/trace.go:171","msg":"trace[456563633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24460; }","duration":"149.026701ms","start":"2026-05-27T20:29:51.402776Z","end":"2026-05-27T20:29:51.551803Z","steps":["trace[456563633] 'agreement among raft nodes before linearized reading' (duration: 148.92769ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:51.672711Z","caller":"traceutil/trace.go:171","msg":"trace[937798213] transaction","detail":"{read_only:false; response_revision:24461; number_of_response:1; }","duration":"117.692086ms","start":"2026-05-27T20:29:51.554987Z","end":"2026-05-27T20:29:51.672679Z","steps":["trace[937798213] 'process raft request' (duration: 104.497335ms)","trace[937798213] 'compare' (duration: 12.921886ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:29:51.7046Z","caller":"traceutil/trace.go:171","msg":"trace[1191979613] transaction","detail":"{read_only:false; response_revision:24462; number_of_response:1; }","duration":"148.666945ms","start":"2026-05-27T20:29:51.555907Z","end":"2026-05-27T20:29:51.704574Z","steps":["trace[1191979613] 'process raft request' (duration: 148.491392ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:51.704838Z","caller":"traceutil/trace.go:171","msg":"trace[1936413991] transaction","detail":"{read_only:false; response_revision:24463; number_of_response:1; }","duration":"148.625414ms","start":"2026-05-27T20:29:51.556188Z","end":"2026-05-27T20:29:51.704814Z","steps":["trace[1936413991] 'process raft request' (duration: 148.340079ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:51.704939Z","caller":"traceutil/trace.go:171","msg":"trace[309460543] transaction","detail":"{read_only:false; response_revision:24464; number_of_response:1; }","duration":"147.564413ms","start":"2026-05-27T20:29:51.557353Z","end":"2026-05-27T20:29:51.704917Z","steps":["trace[309460543] 'process raft request' (duration: 147.39293ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:52.14039Z","caller":"traceutil/trace.go:171","msg":"trace[2114206506] transaction","detail":"{read_only:false; response_revision:24466; number_of_response:1; }","duration":"187.617934ms","start":"2026-05-27T20:29:51.952741Z","end":"2026-05-27T20:29:52.140359Z","steps":["trace[2114206506] 'process raft request' (duration: 185.127127ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:52.140612Z","caller":"traceutil/trace.go:171","msg":"trace[1954520409] transaction","detail":"{read_only:false; response_revision:24467; number_of_response:1; }","duration":"130.46437ms","start":"2026-05-27T20:29:52.010116Z","end":"2026-05-27T20:29:52.14058Z","steps":["trace[1954520409] 'process raft request' (duration: 130.164634ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:52.140709Z","caller":"traceutil/trace.go:171","msg":"trace[1870944913] transaction","detail":"{read_only:false; response_revision:24468; number_of_response:1; }","duration":"124.348712ms","start":"2026-05-27T20:29:52.016345Z","end":"2026-05-27T20:29:52.140694Z","steps":["trace[1870944913] 'process raft request' (duration: 124.160868ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:52.445512Z","caller":"traceutil/trace.go:171","msg":"trace[2065160363] transaction","detail":"{read_only:false; response_revision:24469; number_of_response:1; }","duration":"202.669532ms","start":"2026-05-27T20:29:52.242767Z","end":"2026-05-27T20:29:52.445437Z","steps":["trace[2065160363] 'process raft request' (duration: 202.503018ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:52.590398Z","caller":"traceutil/trace.go:171","msg":"trace[888879835] transaction","detail":"{read_only:false; response_revision:24470; number_of_response:1; }","duration":"130.097423ms","start":"2026-05-27T20:29:52.46027Z","end":"2026-05-27T20:29:52.590368Z","steps":["trace[888879835] 'process raft request' (duration: 128.39271ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:53.351382Z","caller":"traceutil/trace.go:171","msg":"trace[443715039] transaction","detail":"{read_only:false; response_revision:24475; number_of_response:1; }","duration":"208.114734ms","start":"2026-05-27T20:29:53.143237Z","end":"2026-05-27T20:29:53.351352Z","steps":["trace[443715039] 'process raft request' (duration: 207.931381ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:53.3514Z","caller":"traceutil/trace.go:171","msg":"trace[1614742577] transaction","detail":"{read_only:false; response_revision:24474; number_of_response:1; }","duration":"208.42815ms","start":"2026-05-27T20:29:53.142894Z","end":"2026-05-27T20:29:53.351323Z","steps":["trace[1614742577] 'process raft request' (duration: 208.110554ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:53.845765Z","caller":"traceutil/trace.go:171","msg":"trace[609097265] transaction","detail":"{read_only:false; response_revision:24477; number_of_response:1; }","duration":"122.033449ms","start":"2026-05-27T20:29:53.723713Z","end":"2026-05-27T20:29:53.845746Z","steps":["trace[609097265] 'process raft request' (duration: 121.888256ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:54.065805Z","caller":"traceutil/trace.go:171","msg":"trace[2007767109] linearizableReadLoop","detail":"{readStateIndex:25275; appliedIndex:25272; }","duration":"341.475108ms","start":"2026-05-27T20:29:53.724303Z","end":"2026-05-27T20:29:54.065778Z","steps":["trace[2007767109] 'read index received' (duration: 121.290135ms)","trace[2007767109] 'applied index is now lower than readState.Index' (duration: 220.183663ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:29:54.066519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.456571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-27T20:29:54.06658Z","caller":"traceutil/trace.go:171","msg":"trace[359851230] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:24480; }","duration":"228.565493ms","start":"2026-05-27T20:29:53.838001Z","end":"2026-05-27T20:29:54.066566Z","steps":["trace[359851230] 'agreement among raft nodes before linearized reading' (duration: 228.439381ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:54.066587Z","caller":"traceutil/trace.go:171","msg":"trace[400129083] transaction","detail":"{read_only:false; response_revision:24479; number_of_response:1; }","duration":"342.348285ms","start":"2026-05-27T20:29:53.724229Z","end":"2026-05-27T20:29:54.066578Z","steps":["trace[400129083] 'process raft request' (duration: 341.464069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:29:54.066712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:29:53.724222Z","time spent":"342.407127ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:29:54.06682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.466974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:29:54.066859Z","caller":"traceutil/trace.go:171","msg":"trace[1559412040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24480; }","duration":"156.552125ms","start":"2026-05-27T20:29:53.910297Z","end":"2026-05-27T20:29:54.066849Z","steps":["trace[1559412040] 'agreement among raft nodes before linearized reading' (duration: 156.490674ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:54.066922Z","caller":"traceutil/trace.go:171","msg":"trace[1695566024] transaction","detail":"{read_only:false; response_revision:24480; number_of_response:1; }","duration":"342.421477ms","start":"2026-05-27T20:29:53.724492Z","end":"2026-05-27T20:29:54.066913Z","steps":["trace[1695566024] 'process raft request' (duration: 341.245654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:29:54.066971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:29:53.724482Z","time spent":"342.465478ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:29:54.067021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.560357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:29:54.067061Z","caller":"traceutil/trace.go:171","msg":"trace[1093342191] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:24480; }","duration":"174.638119ms","start":"2026-05-27T20:29:53.89241Z","end":"2026-05-27T20:29:54.067049Z","steps":["trace[1093342191] 'agreement among raft nodes before linearized reading' (duration: 174.575898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:29:54.067163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.858395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-27T20:29:54.067195Z","caller":"traceutil/trace.go:171","msg":"trace[719214406] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24480; }","duration":"342.926107ms","start":"2026-05-27T20:29:53.724261Z","end":"2026-05-27T20:29:54.067187Z","steps":["trace[719214406] 'agreement among raft nodes before linearized reading' (duration: 342.794944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:29:54.06722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:29:53.724249Z","time spent":"342.966237ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-27T20:29:54.06651Z","caller":"traceutil/trace.go:171","msg":"trace[212222984] transaction","detail":"{read_only:false; response_revision:24478; number_of_response:1; }","duration":"342.442016ms","start":"2026-05-27T20:29:53.724032Z","end":"2026-05-27T20:29:54.066474Z","steps":["trace[212222984] 'process raft request' (duration: 341.513019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:29:54.067369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:29:53.724011Z","time spent":"343.336965ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:29:54.315146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.780093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:29:54.315226Z","caller":"traceutil/trace.go:171","msg":"trace[736183368] transaction","detail":"{read_only:false; response_revision:24481; number_of_response:1; }","duration":"168.525612ms","start":"2026-05-27T20:29:54.146688Z","end":"2026-05-27T20:29:54.315214Z","steps":["trace[736183368] 'process raft request' (duration: 45.575226ms)","trace[736183368] 'compare' (duration: 122.688641ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:29:54.331094Z","caller":"traceutil/trace.go:171","msg":"trace[2048537098] transaction","detail":"{read_only:false; response_revision:24482; number_of_response:1; }","duration":"182.822673ms","start":"2026-05-27T20:29:54.148237Z","end":"2026-05-27T20:29:54.33106Z","steps":["trace[2048537098] 'process raft request' (duration: 182.615589ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:54.331114Z","caller":"traceutil/trace.go:171","msg":"trace[1125383125] transaction","detail":"{read_only:false; response_revision:24483; number_of_response:1; }","duration":"162.256843ms","start":"2026-05-27T20:29:54.168824Z","end":"2026-05-27T20:29:54.331081Z","steps":["trace[1125383125] 'process raft request' (duration: 162.164281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:29:54.331409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.601642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:29:54.331458Z","caller":"traceutil/trace.go:171","msg":"trace[851545918] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24483; }","duration":"129.688824ms","start":"2026-05-27T20:29:54.201754Z","end":"2026-05-27T20:29:54.331443Z","steps":["trace[851545918] 'agreement among raft nodes before linearized reading' (duration: 129.573982ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:54.331061Z","caller":"traceutil/trace.go:171","msg":"trace[774939698] linearizableReadLoop","detail":"{readStateIndex:25279; appliedIndex:25277; }","duration":"129.240616ms","start":"2026-05-27T20:29:54.201795Z","end":"2026-05-27T20:29:54.331035Z","steps":["trace[774939698] 'read index received' (duration: 128.988081ms)","trace[774939698] 'applied index is now lower than readState.Index' (duration: 251.295µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:29:54.557409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.275437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:29:54.557532Z","caller":"traceutil/trace.go:171","msg":"trace[1736951225] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:24483; }","duration":"108.4138ms","start":"2026-05-27T20:29:54.449059Z","end":"2026-05-27T20:29:54.557473Z","steps":["trace[1736951225] 'count revisions from in-memory index tree' (duration: 108.189126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:29:54.557561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.804685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:29:54.557652Z","caller":"traceutil/trace.go:171","msg":"trace[1531502331] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:24483; }","duration":"112.032168ms","start":"2026-05-27T20:29:54.445596Z","end":"2026-05-27T20:29:54.557629Z","steps":["trace[1531502331] 'count revisions from in-memory index tree' (duration: 111.666492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:29:55.213565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.245068ms","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-27T20:29:55.21366Z","caller":"traceutil/trace.go:171","msg":"trace[1607820195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24484; }","duration":"146.384091ms","start":"2026-05-27T20:29:55.067255Z","end":"2026-05-27T20:29:55.213639Z","steps":["trace[1607820195] 'range keys from in-memory index tree' (duration: 146.013584ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:55.257943Z","caller":"traceutil/trace.go:171","msg":"trace[1920607416] transaction","detail":"{read_only:false; response_revision:24485; number_of_response:1; }","duration":"123.786322ms","start":"2026-05-27T20:29:55.134132Z","end":"2026-05-27T20:29:55.257918Z","steps":["trace[1920607416] 'process raft request' (duration: 123.592488ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:55.480403Z","caller":"traceutil/trace.go:171","msg":"trace[1643719685] transaction","detail":"{read_only:false; response_revision:24486; number_of_response:1; }","duration":"307.515573ms","start":"2026-05-27T20:29:55.172863Z","end":"2026-05-27T20:29:55.480379Z","steps":["trace[1643719685] 'process raft request' (duration: 284.491986ms)","trace[1643719685] 'compare' (duration: 22.866884ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:29:55.480537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:29:55.172837Z","time spent":"307.629576ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:29:55.480669Z","caller":"traceutil/trace.go:171","msg":"trace[2061680051] transaction","detail":"{read_only:false; response_revision:24487; number_of_response:1; }","duration":"263.053188ms","start":"2026-05-27T20:29:55.217606Z","end":"2026-05-27T20:29:55.480659Z","steps":["trace[2061680051] 'process raft request' (duration: 262.736043ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:55.531283Z","caller":"traceutil/trace.go:171","msg":"trace[15625370] transaction","detail":"{read_only:false; response_revision:24489; number_of_response:1; }","duration":"174.678399ms","start":"2026-05-27T20:29:55.356579Z","end":"2026-05-27T20:29:55.531257Z","steps":["trace[15625370] 'process raft request' (duration: 174.622778ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:55.531628Z","caller":"traceutil/trace.go:171","msg":"trace[1115736778] transaction","detail":"{read_only:false; response_revision:24488; number_of_response:1; }","duration":"175.180468ms","start":"2026-05-27T20:29:55.356432Z","end":"2026-05-27T20:29:55.531613Z","steps":["trace[1115736778] 'process raft request' (duration: 174.549707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:29:55.876509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.349101ms","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:6"} {"level":"info","ts":"2026-05-27T20:29:55.876565Z","caller":"traceutil/trace.go:171","msg":"trace[251077264] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:24489; }","duration":"296.451454ms","start":"2026-05-27T20:29:55.5801Z","end":"2026-05-27T20:29:55.876552Z","steps":["trace[251077264] 'count revisions from in-memory index tree' (duration: 296.2669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:29:55.876555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.41065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T20:29:55.876617Z","caller":"traceutil/trace.go:171","msg":"trace[1329845966] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24489; }","duration":"289.546872ms","start":"2026-05-27T20:29:55.587053Z","end":"2026-05-27T20:29:55.8766Z","steps":["trace[1329845966] 'range keys from in-memory index tree' (duration: 289.256668ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:55.954633Z","caller":"traceutil/trace.go:171","msg":"trace[1290233752] transaction","detail":"{read_only:false; response_revision:24490; number_of_response:1; }","duration":"274.769931ms","start":"2026-05-27T20:29:55.679848Z","end":"2026-05-27T20:29:55.954618Z","steps":["trace[1290233752] 'process raft request' (duration: 274.538637ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:55.954573Z","caller":"traceutil/trace.go:171","msg":"trace[169888826] linearizableReadLoop","detail":"{readStateIndex:25286; appliedIndex:25285; }","duration":"104.523836ms","start":"2026-05-27T20:29:55.850025Z","end":"2026-05-27T20:29:55.954549Z","steps":["trace[169888826] 'read index received' (duration: 104.314352ms)","trace[169888826] 'applied index is now lower than readState.Index' (duration: 208.534µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:29:55.955008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.952054ms","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-27T20:29:55.955109Z","caller":"traceutil/trace.go:171","msg":"trace[1927798663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24490; }","duration":"105.104208ms","start":"2026-05-27T20:29:55.849982Z","end":"2026-05-27T20:29:55.955086Z","steps":["trace[1927798663] 'agreement among raft nodes before linearized reading' (duration: 104.786902ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:56.152656Z","caller":"traceutil/trace.go:171","msg":"trace[733363718] linearizableReadLoop","detail":"{readStateIndex:25291; appliedIndex:25290; }","duration":"160.122953ms","start":"2026-05-27T20:29:55.992509Z","end":"2026-05-27T20:29:56.152632Z","steps":["trace[733363718] 'read index received' (duration: 159.923939ms)","trace[733363718] 'applied index is now lower than readState.Index' (duration: 197.664µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:29:56.152898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.366147ms","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-27T20:29:56.15294Z","caller":"traceutil/trace.go:171","msg":"trace[1012923929] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24494; }","duration":"160.458038ms","start":"2026-05-27T20:29:55.99247Z","end":"2026-05-27T20:29:56.152928Z","steps":["trace[1012923929] 'agreement among raft nodes before linearized reading' (duration: 160.289135ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:56.36873Z","caller":"traceutil/trace.go:171","msg":"trace[1810805104] transaction","detail":"{read_only:false; response_revision:24499; number_of_response:1; }","duration":"120.141473ms","start":"2026-05-27T20:29:56.248552Z","end":"2026-05-27T20:29:56.368694Z","steps":["trace[1810805104] 'process raft request' (duration: 114.233951ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:56.568007Z","caller":"traceutil/trace.go:171","msg":"trace[1742561398] transaction","detail":"{read_only:false; response_revision:24502; number_of_response:1; }","duration":"230.751265ms","start":"2026-05-27T20:29:56.337233Z","end":"2026-05-27T20:29:56.567984Z","steps":["trace[1742561398] 'process raft request' (duration: 230.706554ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:29:56.5684Z","caller":"traceutil/trace.go:171","msg":"trace[1951363846] transaction","detail":"{read_only:false; response_revision:24500; number_of_response:1; }","duration":"244.403845ms","start":"2026-05-27T20:29:56.323982Z","end":"2026-05-27T20:29:56.568386Z","steps":["trace[1951363846] 'process raft request' (duration: 214.867923ms)","trace[1951363846] 'compare' (duration: 28.485652ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:29:56.568544Z","caller":"traceutil/trace.go:171","msg":"trace[2048454274] transaction","detail":"{read_only:false; response_revision:24501; number_of_response:1; }","duration":"231.367537ms","start":"2026-05-27T20:29:56.337167Z","end":"2026-05-27T20:29:56.568535Z","steps":["trace[2048454274] 'process raft request' (duration: 230.688713ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:02.731213Z","caller":"traceutil/trace.go:171","msg":"trace[1619783501] transaction","detail":"{read_only:false; response_revision:24558; number_of_response:1; }","duration":"139.983779ms","start":"2026-05-27T20:30:02.59121Z","end":"2026-05-27T20:30:02.731194Z","steps":["trace[1619783501] 'process raft request' (duration: 139.835316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:03.028444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.693147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:30:03.028699Z","caller":"traceutil/trace.go:171","msg":"trace[874874949] linearizableReadLoop","detail":"{readStateIndex:25359; appliedIndex:25357; }","duration":"119.765296ms","start":"2026-05-27T20:30:02.908909Z","end":"2026-05-27T20:30:03.028674Z","steps":["trace[874874949] 'read index received' (duration: 34.621µs)","trace[874874949] 'applied index is now lower than readState.Index' (duration: 119.729365ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:30:03.02883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.916039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:30:03.02886Z","caller":"traceutil/trace.go:171","msg":"trace[744554917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24560; }","duration":"119.96865ms","start":"2026-05-27T20:30:02.908879Z","end":"2026-05-27T20:30:03.028847Z","steps":["trace[744554917] 'agreement among raft nodes before linearized reading' (duration: 119.868188ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:03.029089Z","caller":"traceutil/trace.go:171","msg":"trace[496391477] transaction","detail":"{read_only:false; response_revision:24560; number_of_response:1; }","duration":"436.656767ms","start":"2026-05-27T20:30:02.592392Z","end":"2026-05-27T20:30:03.029049Z","steps":["trace[496391477] 'process raft request' (duration: 436.193418ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:03.029089Z","caller":"traceutil/trace.go:171","msg":"trace[1862428504] transaction","detail":"{read_only:false; response_revision:24559; number_of_response:1; }","duration":"436.858871ms","start":"2026-05-27T20:30:02.592185Z","end":"2026-05-27T20:30:03.029044Z","steps":["trace[1862428504] 'process raft request' (duration: 288.439331ms)","trace[1862428504] 'compare' (duration: 147.482761ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:30:03.029287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:02.592376Z","time spent":"436.804059ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:30:03.029373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:02.592151Z","time spent":"437.127465ms","remote":"127.0.0.1:51044","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-27T20:30:03.352196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.620788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-05-27T20:30:03.352315Z","caller":"traceutil/trace.go:171","msg":"trace[816814357] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:24560; }","duration":"174.755811ms","start":"2026-05-27T20:30:03.17753Z","end":"2026-05-27T20:30:03.352286Z","steps":["trace[816814357] 'range keys from in-memory index tree' (duration: 174.385393ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:03.522143Z","caller":"traceutil/trace.go:171","msg":"trace[1339694721] linearizableReadLoop","detail":"{readStateIndex:25360; appliedIndex:25359; }","duration":"166.292761ms","start":"2026-05-27T20:30:03.355815Z","end":"2026-05-27T20:30:03.522108Z","steps":["trace[1339694721] 'read index received' (duration: 166.087047ms)","trace[1339694721] 'applied index is now lower than readState.Index' (duration: 204.214µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:30:03.522309Z","caller":"traceutil/trace.go:171","msg":"trace[1296098033] transaction","detail":"{read_only:false; response_revision:24561; number_of_response:1; }","duration":"184.786091ms","start":"2026-05-27T20:30:03.337509Z","end":"2026-05-27T20:30:03.522295Z","steps":["trace[1296098033] 'process raft request' (duration: 184.369444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:03.522453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.486025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:30:03.522606Z","caller":"traceutil/trace.go:171","msg":"trace[1384303079] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24561; }","duration":"145.688419ms","start":"2026-05-27T20:30:03.376892Z","end":"2026-05-27T20:30:03.52258Z","steps":["trace[1384303079] 'agreement among raft nodes before linearized reading' (duration: 145.489485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:03.522655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.81914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-22c784e9-83cc-4eb8-9de3-6ee0629016dd\" ","response":"range_response_count:1 size:1284"} {"level":"info","ts":"2026-05-27T20:30:03.522736Z","caller":"traceutil/trace.go:171","msg":"trace[1373506591] range","detail":"{range_begin:/registry/persistentvolumes/pvc-22c784e9-83cc-4eb8-9de3-6ee0629016dd; range_end:; response_count:1; response_revision:24561; }","duration":"166.955933ms","start":"2026-05-27T20:30:03.355758Z","end":"2026-05-27T20:30:03.522714Z","steps":["trace[1373506591] 'agreement among raft nodes before linearized reading' (duration: 166.727679ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:03.757511Z","caller":"traceutil/trace.go:171","msg":"trace[707221469] transaction","detail":"{read_only:false; response_revision:24562; number_of_response:1; }","duration":"175.676858ms","start":"2026-05-27T20:30:03.581767Z","end":"2026-05-27T20:30:03.757444Z","steps":["trace[707221469] 'process raft request' (duration: 149.679405ms)","trace[707221469] 'compare' (duration: 25.760509ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:30:03.757551Z","caller":"traceutil/trace.go:171","msg":"trace[437610563] transaction","detail":"{read_only:false; response_revision:24563; number_of_response:1; }","duration":"155.137029ms","start":"2026-05-27T20:30:03.602382Z","end":"2026-05-27T20:30:03.757519Z","steps":["trace[437610563] 'process raft request' (duration: 154.978286ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:03.757832Z","caller":"traceutil/trace.go:171","msg":"trace[1480353641] linearizableReadLoop","detail":"{readStateIndex:25363; appliedIndex:25360; }","duration":"146.084845ms","start":"2026-05-27T20:30:03.611693Z","end":"2026-05-27T20:30:03.757778Z","steps":["trace[1480353641] 'read index received' (duration: 119.729445ms)","trace[1480353641] 'applied index is now lower than readState.Index' (duration: 26.35101ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:30:03.757947Z","caller":"traceutil/trace.go:171","msg":"trace[1383824926] transaction","detail":"{read_only:false; response_revision:24564; number_of_response:1; }","duration":"152.519977ms","start":"2026-05-27T20:30:03.605411Z","end":"2026-05-27T20:30:03.757931Z","steps":["trace[1383824926] 'process raft request' (duration: 152.010098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:03.758196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.473992ms","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-27T20:30:03.758375Z","caller":"traceutil/trace.go:171","msg":"trace[1132176374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24564; }","duration":"146.688067ms","start":"2026-05-27T20:30:03.611658Z","end":"2026-05-27T20:30:03.758346Z","steps":["trace[1132176374] 'agreement among raft nodes before linearized reading' (duration: 146.264619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:03.758537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.442217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-27T20:30:03.758616Z","caller":"traceutil/trace.go:171","msg":"trace[257054454] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24564; }","duration":"141.57071ms","start":"2026-05-27T20:30:03.617023Z","end":"2026-05-27T20:30:03.758594Z","steps":["trace[257054454] 'agreement among raft nodes before linearized reading' (duration: 141.293885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:04.050295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.080396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:30:04.050384Z","caller":"traceutil/trace.go:171","msg":"trace[66366936] transaction","detail":"{read_only:false; response_revision:24565; number_of_response:1; }","duration":"286.082125ms","start":"2026-05-27T20:30:03.764286Z","end":"2026-05-27T20:30:04.050368Z","steps":["trace[66366936] 'process raft request' (duration: 160.856497ms)","trace[66366936] 'compare' (duration: 124.736629ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:30:04.369843Z","caller":"traceutil/trace.go:171","msg":"trace[706132638] linearizableReadLoop","detail":"{readStateIndex:25365; appliedIndex:25363; }","duration":"459.174044ms","start":"2026-05-27T20:30:03.910631Z","end":"2026-05-27T20:30:04.369805Z","steps":["trace[706132638] 'read index received' (duration: 14.501796ms)","trace[706132638] 'applied index is now lower than readState.Index' (duration: 444.670588ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:30:04.370197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.514382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:30:04.370292Z","caller":"traceutil/trace.go:171","msg":"trace[342149605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24566; }","duration":"459.681386ms","start":"2026-05-27T20:30:03.910582Z","end":"2026-05-27T20:30:04.370263Z","steps":["trace[342149605] 'agreement among raft nodes before linearized reading' (duration: 459.486322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:04.370343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:03.910559Z","time spent":"459.772177ms","remote":"127.0.0.1:50794","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-27T20:30:04.3704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.316628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T20:30:04.370456Z","caller":"traceutil/trace.go:171","msg":"trace[561840881] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24566; }","duration":"369.412659ms","start":"2026-05-27T20:30:04.001026Z","end":"2026-05-27T20:30:04.370438Z","steps":["trace[561840881] 'agreement among raft nodes before linearized reading' (duration: 369.212815ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:04.370435Z","caller":"traceutil/trace.go:171","msg":"trace[1650015838] transaction","detail":"{read_only:false; response_revision:24566; number_of_response:1; }","duration":"605.573847ms","start":"2026-05-27T20:30:03.764805Z","end":"2026-05-27T20:30:04.370378Z","steps":["trace[1650015838] 'process raft request' (duration: 582.778383ms)","trace[1650015838] 'compare' (duration: 21.695043ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:30:04.370507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:04.001008Z","time spent":"369.484081ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T20:30:04.370494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.939591ms","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-27T20:30:04.370571Z","caller":"traceutil/trace.go:171","msg":"trace[1128373899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24566; }","duration":"369.034352ms","start":"2026-05-27T20:30:04.001516Z","end":"2026-05-27T20:30:04.37055Z","steps":["trace[1128373899] 'agreement among raft nodes before linearized reading' (duration: 368.831779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:04.370615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:04.00151Z","time spent":"369.095833ms","remote":"127.0.0.1:51044","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-27T20:30:04.370652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:03.764784Z","time spent":"605.749701ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:30:09.412251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.719538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:30:09.412374Z","caller":"traceutil/trace.go:171","msg":"trace[1847076368] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:24610; }","duration":"155.902892ms","start":"2026-05-27T20:30:09.256441Z","end":"2026-05-27T20:30:09.412344Z","steps":["trace[1847076368] 'count revisions from in-memory index tree' (duration: 155.569186ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:09.831439Z","caller":"traceutil/trace.go:171","msg":"trace[1870151434] linearizableReadLoop","detail":"{readStateIndex:25411; appliedIndex:25410; }","duration":"281.963408ms","start":"2026-05-27T20:30:09.549453Z","end":"2026-05-27T20:30:09.831416Z","steps":["trace[1870151434] 'read index received' (duration: 281.824756ms)","trace[1870151434] 'applied index is now lower than readState.Index' (duration: 134.162µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:30:09.831562Z","caller":"traceutil/trace.go:171","msg":"trace[1763052742] transaction","detail":"{read_only:false; response_revision:24611; number_of_response:1; }","duration":"348.363059ms","start":"2026-05-27T20:30:09.48315Z","end":"2026-05-27T20:30:09.831513Z","steps":["trace[1763052742] 'process raft request' (duration: 348.094314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:09.831666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.195532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-27T20:30:09.831855Z","caller":"traceutil/trace.go:171","msg":"trace[614321583] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24611; }","duration":"282.436997ms","start":"2026-05-27T20:30:09.549409Z","end":"2026-05-27T20:30:09.831846Z","steps":["trace[614321583] 'agreement among raft nodes before linearized reading' (duration: 282.119841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:09.832291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:09.483128Z","time spent":"348.652354ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:30:10.032692Z","caller":"traceutil/trace.go:171","msg":"trace[2078933344] transaction","detail":"{read_only:false; response_revision:24612; number_of_response:1; }","duration":"482.792934ms","start":"2026-05-27T20:30:09.549864Z","end":"2026-05-27T20:30:10.032657Z","steps":["trace[2078933344] 'process raft request' (duration: 442.804754ms)","trace[2078933344] 'compare' (duration: 39.616513ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:30:10.032741Z","caller":"traceutil/trace.go:171","msg":"trace[516906241] transaction","detail":"{read_only:false; response_revision:24614; number_of_response:1; }","duration":"236.987063ms","start":"2026-05-27T20:30:09.79574Z","end":"2026-05-27T20:30:10.032727Z","steps":["trace[516906241] 'process raft request' (duration: 236.922362ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:10.032762Z","caller":"traceutil/trace.go:171","msg":"trace[72077238] transaction","detail":"{read_only:false; response_revision:24613; number_of_response:1; }","duration":"251.095271ms","start":"2026-05-27T20:30:09.781641Z","end":"2026-05-27T20:30:10.032737Z","steps":["trace[72077238] 'process raft request' (duration: 250.943369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:10.03281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:09.549853Z","time spent":"482.895846ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:30:10.032895Z","caller":"traceutil/trace.go:171","msg":"trace[2011217079] linearizableReadLoop","detail":"{readStateIndex:25415; appliedIndex:25411; }","duration":"201.168742ms","start":"2026-05-27T20:30:09.83172Z","end":"2026-05-27T20:30:10.032889Z","steps":["trace[2011217079] 'read index received' (duration: 160.838536ms)","trace[2011217079] 'applied index is now lower than readState.Index' (duration: 40.328926ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:30:10.032948Z","caller":"traceutil/trace.go:171","msg":"trace[1079128304] transaction","detail":"{read_only:false; response_revision:24615; number_of_response:1; }","duration":"234.821682ms","start":"2026-05-27T20:30:09.798122Z","end":"2026-05-27T20:30:10.032943Z","steps":["trace[1079128304] 'process raft request' (duration: 234.565028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:10.033023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.149085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:30:10.033045Z","caller":"traceutil/trace.go:171","msg":"trace[1272290522] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:24615; }","duration":"208.221916ms","start":"2026-05-27T20:30:09.824816Z","end":"2026-05-27T20:30:10.033038Z","steps":["trace[1272290522] 'agreement among raft nodes before linearized reading' (duration: 208.180865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:10.033065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.384101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-27T20:30:10.033096Z","caller":"traceutil/trace.go:171","msg":"trace[803209092] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24615; }","duration":"196.449593ms","start":"2026-05-27T20:30:09.836638Z","end":"2026-05-27T20:30:10.033088Z","steps":["trace[803209092] 'agreement among raft nodes before linearized reading' (duration: 196.334641ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:12.228971Z","caller":"traceutil/trace.go:171","msg":"trace[728808457] transaction","detail":"{read_only:false; response_revision:24632; number_of_response:1; }","duration":"187.264489ms","start":"2026-05-27T20:30:12.041685Z","end":"2026-05-27T20:30:12.228949Z","steps":["trace[728808457] 'process raft request' (duration: 187.231108ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:12.229229Z","caller":"traceutil/trace.go:171","msg":"trace[382286597] transaction","detail":"{read_only:false; response_revision:24631; number_of_response:1; }","duration":"187.779859ms","start":"2026-05-27T20:30:12.041425Z","end":"2026-05-27T20:30:12.229205Z","steps":["trace[382286597] 'process raft request' (duration: 187.444442ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:12.229365Z","caller":"traceutil/trace.go:171","msg":"trace[640212039] transaction","detail":"{read_only:false; response_revision:24630; number_of_response:1; }","duration":"187.959441ms","start":"2026-05-27T20:30:12.04139Z","end":"2026-05-27T20:30:12.229349Z","steps":["trace[640212039] 'process raft request' (duration: 101.001379ms)","trace[640212039] 'compare' (duration: 86.30726ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:30:16.183338Z","caller":"traceutil/trace.go:171","msg":"trace[984002702] transaction","detail":"{read_only:false; response_revision:24727; number_of_response:1; }","duration":"194.613377ms","start":"2026-05-27T20:30:15.988699Z","end":"2026-05-27T20:30:16.183312Z","steps":["trace[984002702] 'process raft request' (duration: 194.454344ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:20.225054Z","caller":"traceutil/trace.go:171","msg":"trace[557052162] transaction","detail":"{read_only:false; response_revision:24773; number_of_response:1; }","duration":"245.797211ms","start":"2026-05-27T20:30:19.979223Z","end":"2026-05-27T20:30:20.22502Z","steps":["trace[557052162] 'process raft request' (duration: 245.545866ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:20.577142Z","caller":"traceutil/trace.go:171","msg":"trace[683570439] transaction","detail":"{read_only:false; response_revision:24777; number_of_response:1; }","duration":"345.675188ms","start":"2026-05-27T20:30:20.23144Z","end":"2026-05-27T20:30:20.577115Z","steps":["trace[683570439] 'process raft request' (duration: 345.591576ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:20.577203Z","caller":"traceutil/trace.go:171","msg":"trace[1505101322] transaction","detail":"{read_only:false; response_revision:24776; number_of_response:1; }","duration":"346.198989ms","start":"2026-05-27T20:30:20.230956Z","end":"2026-05-27T20:30:20.577155Z","steps":["trace[1505101322] 'process raft request' (duration: 345.970684ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:20.577232Z","caller":"traceutil/trace.go:171","msg":"trace[2089678157] transaction","detail":"{read_only:false; response_revision:24775; number_of_response:1; }","duration":"346.618016ms","start":"2026-05-27T20:30:20.230593Z","end":"2026-05-27T20:30:20.577211Z","steps":["trace[2089678157] 'process raft request' (duration: 336.431622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:20.577298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:20.231427Z","time spent":"345.79565ms","remote":"127.0.0.1:51050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:30:20.57735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:20.230571Z","time spent":"346.709258ms","remote":"127.0.0.1:50930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:30:20.577353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:20.230922Z","time spent":"346.352371ms","remote":"127.0.0.1:51050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:30:20.577419Z","caller":"traceutil/trace.go:171","msg":"trace[848159668] linearizableReadLoop","detail":"{readStateIndex:25581; appliedIndex:25577; }","duration":"320.425258ms","start":"2026-05-27T20:30:20.256948Z","end":"2026-05-27T20:30:20.577373Z","steps":["trace[848159668] 'read index received' (duration: 310.052601ms)","trace[848159668] 'applied index is now lower than readState.Index' (duration: 10.371267ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:30:20.577561Z","caller":"traceutil/trace.go:171","msg":"trace[1464692075] transaction","detail":"{read_only:false; response_revision:24778; number_of_response:1; }","duration":"345.080976ms","start":"2026-05-27T20:30:20.232465Z","end":"2026-05-27T20:30:20.577546Z","steps":["trace[1464692075] 'process raft request' (duration: 344.609117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:20.577793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:20.232455Z","time spent":"345.25928ms","remote":"127.0.0.1:50930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:30:20.577718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.770854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18571"} {"level":"info","ts":"2026-05-27T20:30:20.578377Z","caller":"traceutil/trace.go:171","msg":"trace[479198299] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24778; }","duration":"321.448157ms","start":"2026-05-27T20:30:20.256914Z","end":"2026-05-27T20:30:20.578362Z","steps":["trace[479198299] 'agreement among raft nodes before linearized reading' (duration: 320.693323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:20.578416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:20.256893Z","time spent":"321.509948ms","remote":"127.0.0.1:50940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18594,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-27T20:30:20.583295Z","caller":"traceutil/trace.go:171","msg":"trace[644639267] transaction","detail":"{read_only:false; response_revision:24779; number_of_response:1; }","duration":"254.936124ms","start":"2026-05-27T20:30:20.328334Z","end":"2026-05-27T20:30:20.583271Z","steps":["trace[644639267] 'process raft request' (duration: 254.74429ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:20.583294Z","caller":"traceutil/trace.go:171","msg":"trace[1346380014] transaction","detail":"{read_only:false; response_revision:24780; number_of_response:1; }","duration":"254.762201ms","start":"2026-05-27T20:30:20.32851Z","end":"2026-05-27T20:30:20.583273Z","steps":["trace[1346380014] 'process raft request' (duration: 254.695ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:20.583709Z","caller":"traceutil/trace.go:171","msg":"trace[148803455] transaction","detail":"{read_only:false; response_revision:24781; number_of_response:1; }","duration":"253.333063ms","start":"2026-05-27T20:30:20.330367Z","end":"2026-05-27T20:30:20.5837Z","steps":["trace[148803455] 'process raft request' (duration: 252.872154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:20.583851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.134601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-05-27T20:30:20.583813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.840162ms","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-27T20:30:20.583881Z","caller":"traceutil/trace.go:171","msg":"trace[239470168] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24781; }","duration":"100.173013ms","start":"2026-05-27T20:30:20.483698Z","end":"2026-05-27T20:30:20.583871Z","steps":["trace[239470168] 'agreement among raft nodes before linearized reading' (duration: 100.069381ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:20.583902Z","caller":"traceutil/trace.go:171","msg":"trace[537472336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24781; }","duration":"133.971634ms","start":"2026-05-27T20:30:20.449911Z","end":"2026-05-27T20:30:20.583883Z","steps":["trace[537472336] 'agreement among raft nodes before linearized reading' (duration: 133.71975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:20.583909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.32383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T20:30:20.583942Z","caller":"traceutil/trace.go:171","msg":"trace[1183826585] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24781; }","duration":"105.396332ms","start":"2026-05-27T20:30:20.478536Z","end":"2026-05-27T20:30:20.583932Z","steps":["trace[1183826585] 'agreement among raft nodes before linearized reading' (duration: 105.316071ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:32.790489Z","caller":"traceutil/trace.go:171","msg":"trace[1071515393] transaction","detail":"{read_only:false; response_revision:24878; number_of_response:1; }","duration":"162.07177ms","start":"2026-05-27T20:30:32.628396Z","end":"2026-05-27T20:30:32.790468Z","steps":["trace[1071515393] 'process raft request' (duration: 161.924747ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:33.042613Z","caller":"traceutil/trace.go:171","msg":"trace[128014442] transaction","detail":"{read_only:false; response_revision:24879; number_of_response:1; }","duration":"411.481339ms","start":"2026-05-27T20:30:32.631093Z","end":"2026-05-27T20:30:33.042574Z","steps":["trace[128014442] 'process raft request' (duration: 411.116732ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:33.042785Z","caller":"traceutil/trace.go:171","msg":"trace[134977148] linearizableReadLoop","detail":"{readStateIndex:25686; appliedIndex:25683; }","duration":"409.910309ms","start":"2026-05-27T20:30:32.632836Z","end":"2026-05-27T20:30:33.042747Z","steps":["trace[134977148] 'read index received' (duration: 157.514583ms)","trace[134977148] 'applied index is now lower than readState.Index' (duration: 252.393146ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:30:33.042804Z","caller":"traceutil/trace.go:171","msg":"trace[1589061250] transaction","detail":"{read_only:false; response_revision:24880; number_of_response:1; }","duration":"410.238705ms","start":"2026-05-27T20:30:32.63252Z","end":"2026-05-27T20:30:33.042758Z","steps":["trace[1589061250] 'process raft request' (duration: 409.96073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:33.042878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:32.631076Z","time spent":"411.652492ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:30:33.043031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:32.632507Z","time spent":"410.403029ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:30:33.043075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.203995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T20:30:33.043142Z","caller":"traceutil/trace.go:171","msg":"trace[414493712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24880; }","duration":"410.317647ms","start":"2026-05-27T20:30:32.632807Z","end":"2026-05-27T20:30:33.043125Z","steps":["trace[414493712] 'agreement among raft nodes before linearized reading' (duration: 410.046122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:33.043176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:32.632797Z","time spent":"410.373948ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T20:30:33.342574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.019562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:30:33.342891Z","caller":"traceutil/trace.go:171","msg":"trace[2009685435] transaction","detail":"{read_only:false; response_revision:24881; number_of_response:1; }","duration":"505.128418ms","start":"2026-05-27T20:30:32.837741Z","end":"2026-05-27T20:30:33.34287Z","steps":["trace[2009685435] 'process raft request' (duration: 335.725468ms)","trace[2009685435] 'compare' (duration: 168.769728ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:30:33.342934Z","caller":"traceutil/trace.go:171","msg":"trace[1847684298] transaction","detail":"{read_only:false; response_revision:24884; number_of_response:1; }","duration":"199.948228ms","start":"2026-05-27T20:30:33.142968Z","end":"2026-05-27T20:30:33.342916Z","steps":["trace[1847684298] 'process raft request' (duration: 199.894607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:33.342965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:32.837704Z","time spent":"505.23136ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:30:33.343058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.036495ms","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-27T20:30:33.342893Z","caller":"traceutil/trace.go:171","msg":"trace[855265451] linearizableReadLoop","detail":"{readStateIndex:25687; appliedIndex:25686; }","duration":"300.017951ms","start":"2026-05-27T20:30:33.042857Z","end":"2026-05-27T20:30:33.342875Z","steps":["trace[855265451] 'read index received' (duration: 130.645602ms)","trace[855265451] 'applied index is now lower than readState.Index' (duration: 169.370149ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:30:33.343099Z","caller":"traceutil/trace.go:171","msg":"trace[1648905802] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24884; }","duration":"706.101787ms","start":"2026-05-27T20:30:32.636987Z","end":"2026-05-27T20:30:33.343088Z","steps":["trace[1648905802] 'agreement among raft nodes before linearized reading' (duration: 705.940314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:33.343123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:32.636977Z","time spent":"706.140487ms","remote":"127.0.0.1:51044","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-27T20:30:33.34319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.314297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"warn","ts":"2026-05-27T20:30:33.343208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.289215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:30:33.34322Z","caller":"traceutil/trace.go:171","msg":"trace[870373181] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:24884; }","duration":"149.343048ms","start":"2026-05-27T20:30:33.193868Z","end":"2026-05-27T20:30:33.343211Z","steps":["trace[870373181] 'agreement among raft nodes before linearized reading' (duration: 149.271107ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:33.343231Z","caller":"traceutil/trace.go:171","msg":"trace[1958238703] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:24884; }","duration":"341.344836ms","start":"2026-05-27T20:30:33.001879Z","end":"2026-05-27T20:30:33.343224Z","steps":["trace[1958238703] 'agreement among raft nodes before linearized reading' (duration: 341.303175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:33.343263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:33.001853Z","time spent":"341.398647ms","remote":"127.0.0.1:44086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-27T20:30:33.343304Z","caller":"traceutil/trace.go:171","msg":"trace[267613811] transaction","detail":"{read_only:false; response_revision:24883; number_of_response:1; }","duration":"202.933355ms","start":"2026-05-27T20:30:33.140359Z","end":"2026-05-27T20:30:33.343292Z","steps":["trace[267613811] 'process raft request' (duration: 202.455456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:33.343459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.015464ms","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-27T20:30:33.343488Z","caller":"traceutil/trace.go:171","msg":"trace[1586559663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24884; }","duration":"697.065155ms","start":"2026-05-27T20:30:32.646416Z","end":"2026-05-27T20:30:33.343481Z","steps":["trace[1586559663] 'agreement among raft nodes before linearized reading' (duration: 696.999514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:33.343491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.501933ms","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-27T20:30:33.343506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:32.646402Z","time spent":"697.098735ms","remote":"127.0.0.1:51044","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":"info","ts":"2026-05-27T20:30:33.343516Z","caller":"traceutil/trace.go:171","msg":"trace[148805679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24884; }","duration":"697.546993ms","start":"2026-05-27T20:30:32.645963Z","end":"2026-05-27T20:30:33.34351Z","steps":["trace[148805679] 'agreement among raft nodes before linearized reading' (duration: 697.463902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:33.343536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:32.645952Z","time spent":"697.579585ms","remote":"127.0.0.1:51044","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-27T20:30:33.343634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.462935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-27T20:30:33.343653Z","caller":"traceutil/trace.go:171","msg":"trace[795894226] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24884; }","duration":"704.497986ms","start":"2026-05-27T20:30:32.639149Z","end":"2026-05-27T20:30:33.343647Z","steps":["trace[795894226] 'agreement among raft nodes before linearized reading' (duration: 704.441855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:33.343672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:32.63914Z","time spent":"704.526236ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-27T20:30:33.34368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.471109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-27T20:30:33.343701Z","caller":"traceutil/trace.go:171","msg":"trace[1155665770] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:24884; }","duration":"401.493779ms","start":"2026-05-27T20:30:32.942201Z","end":"2026-05-27T20:30:33.343694Z","steps":["trace[1155665770] 'agreement among raft nodes before linearized reading' (duration: 401.347876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:33.343723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:32.94216Z","time spent":"401.556019ms","remote":"127.0.0.1:60192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":163,"response size":32,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-05-27T20:30:33.343261Z","caller":"traceutil/trace.go:171","msg":"trace[897449841] transaction","detail":"{read_only:false; response_revision:24882; number_of_response:1; }","duration":"290.000259ms","start":"2026-05-27T20:30:33.053242Z","end":"2026-05-27T20:30:33.343242Z","steps":["trace[897449841] 'process raft request' (duration: 289.4708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:33.343833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.628098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:30:33.343849Z","caller":"traceutil/trace.go:171","msg":"trace[546841042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24884; }","duration":"434.683129ms","start":"2026-05-27T20:30:32.909161Z","end":"2026-05-27T20:30:33.343844Z","steps":["trace[546841042] 'agreement among raft nodes before linearized reading' (duration: 434.653878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:33.343864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:32.909144Z","time spent":"434.7165ms","remote":"127.0.0.1:50794","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-27T20:30:33.343953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.489163ms","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-27T20:30:33.343975Z","caller":"traceutil/trace.go:171","msg":"trace[1836095127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24884; }","duration":"697.527353ms","start":"2026-05-27T20:30:32.646441Z","end":"2026-05-27T20:30:33.343968Z","steps":["trace[1836095127] 'agreement among raft nodes before linearized reading' (duration: 697.469192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:33.343992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:32.646432Z","time spent":"697.555864ms","remote":"127.0.0.1:51044","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-27T20:30:36.369209Z","caller":"traceutil/trace.go:171","msg":"trace[302278811] transaction","detail":"{read_only:false; response_revision:24905; number_of_response:1; }","duration":"152.149291ms","start":"2026-05-27T20:30:36.217031Z","end":"2026-05-27T20:30:36.36918Z","steps":["trace[302278811] 'process raft request' (duration: 68.3879ms)","trace[302278811] 'compare' (duration: 83.367754ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:30:37.03911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.740571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:30:37.039181Z","caller":"traceutil/trace.go:171","msg":"trace[1137887070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24907; }","duration":"123.879004ms","start":"2026-05-27T20:30:36.915286Z","end":"2026-05-27T20:30:37.039165Z","steps":["trace[1137887070] 'range keys from in-memory index tree' (duration: 123.6686ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:37.175541Z","caller":"traceutil/trace.go:171","msg":"trace[2038980521] transaction","detail":"{read_only:false; response_revision:24908; number_of_response:1; }","duration":"120.247965ms","start":"2026-05-27T20:30:37.055259Z","end":"2026-05-27T20:30:37.175507Z","steps":["trace[2038980521] 'process raft request' (duration: 120.127692ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:37.260054Z","caller":"traceutil/trace.go:171","msg":"trace[1575074300] transaction","detail":"{read_only:false; response_revision:24909; number_of_response:1; }","duration":"204.117089ms","start":"2026-05-27T20:30:37.05588Z","end":"2026-05-27T20:30:37.259997Z","steps":["trace[1575074300] 'process raft request' (duration: 185.24657ms)","trace[1575074300] 'compare' (duration: 18.673415ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:30:37.394263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.328253ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-27T20:30:37.604381Z","caller":"traceutil/trace.go:171","msg":"trace[379353045] transaction","detail":"{read_only:false; response_revision:24911; number_of_response:1; }","duration":"244.885883ms","start":"2026-05-27T20:30:37.359452Z","end":"2026-05-27T20:30:37.604338Z","steps":["trace[379353045] 'process raft request' (duration: 162.885835ms)","trace[379353045] 'compare' (duration: 81.729312ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:30:37.604545Z","caller":"traceutil/trace.go:171","msg":"trace[1641612384] linearizableReadLoop","detail":"{readStateIndex:25718; appliedIndex:25716; }","duration":"244.967124ms","start":"2026-05-27T20:30:37.35954Z","end":"2026-05-27T20:30:37.604507Z","steps":["trace[1641612384] 'read index received' (duration: 48.423839ms)","trace[1641612384] 'applied index is now lower than readState.Index' (duration: 196.541075ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:30:37.604631Z","caller":"traceutil/trace.go:171","msg":"trace[947521963] transaction","detail":"{read_only:false; response_revision:24912; number_of_response:1; }","duration":"245.020445ms","start":"2026-05-27T20:30:37.35958Z","end":"2026-05-27T20:30:37.6046Z","steps":["trace[947521963] 'process raft request' (duration: 244.687439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:37.604808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.244869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-27T20:30:37.604865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.054587ms","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":"warn","ts":"2026-05-27T20:30:37.6049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.018301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-27T20:30:37.604924Z","caller":"traceutil/trace.go:171","msg":"trace[1728748189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24912; }","duration":"245.387712ms","start":"2026-05-27T20:30:37.359518Z","end":"2026-05-27T20:30:37.604905Z","steps":["trace[1728748189] 'agreement among raft nodes before linearized reading' (duration: 245.114337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:37.604907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.599249ms","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-27T20:30:37.604965Z","caller":"traceutil/trace.go:171","msg":"trace[1391805135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24912; }","duration":"194.20617ms","start":"2026-05-27T20:30:37.410737Z","end":"2026-05-27T20:30:37.604943Z","steps":["trace[1391805135] 'agreement among raft nodes before linearized reading' (duration: 193.944725ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:37.604978Z","caller":"traceutil/trace.go:171","msg":"trace[91125161] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24912; }","duration":"190.142933ms","start":"2026-05-27T20:30:37.414813Z","end":"2026-05-27T20:30:37.604956Z","steps":["trace[91125161] 'agreement among raft nodes before linearized reading' (duration: 189.915279ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:37.604993Z","caller":"traceutil/trace.go:171","msg":"trace[1200163308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24912; }","duration":"192.730721ms","start":"2026-05-27T20:30:37.412241Z","end":"2026-05-27T20:30:37.604972Z","steps":["trace[1200163308] 'agreement among raft nodes before linearized reading' (duration: 192.499627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:37.605101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.865613ms","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-27T20:30:37.605169Z","caller":"traceutil/trace.go:171","msg":"trace[659662313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24912; }","duration":"193.966175ms","start":"2026-05-27T20:30:37.411187Z","end":"2026-05-27T20:30:37.605153Z","steps":["trace[659662313] 'agreement among raft nodes before linearized reading' (duration: 193.74083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:37.605215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.292429ms","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-27T20:30:37.605256Z","caller":"traceutil/trace.go:171","msg":"trace[1039259782] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24912; }","duration":"136.37734ms","start":"2026-05-27T20:30:37.468867Z","end":"2026-05-27T20:30:37.605244Z","steps":["trace[1039259782] 'agreement among raft nodes before linearized reading' (duration: 136.091865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:37.605363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.411905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-05-27T20:30:37.605411Z","caller":"traceutil/trace.go:171","msg":"trace[1190990265] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24912; }","duration":"112.503498ms","start":"2026-05-27T20:30:37.492894Z","end":"2026-05-27T20:30:37.605398Z","steps":["trace[1190990265] 'agreement among raft nodes before linearized reading' (duration: 111.917866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:37.606377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.993787ms","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-27T20:30:37.606545Z","caller":"traceutil/trace.go:171","msg":"trace[1276375997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24912; }","duration":"193.309704ms","start":"2026-05-27T20:30:37.413173Z","end":"2026-05-27T20:30:37.606483Z","steps":["trace[1276375997] 'agreement among raft nodes before linearized reading' (duration: 191.57991ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:39.397865Z","caller":"traceutil/trace.go:171","msg":"trace[834690985] transaction","detail":"{read_only:false; response_revision:24933; number_of_response:1; }","duration":"132.801143ms","start":"2026-05-27T20:30:39.265039Z","end":"2026-05-27T20:30:39.39784Z","steps":["trace[834690985] 'process raft request' (duration: 101.663991ms)","trace[834690985] 'compare' (duration: 30.676043ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:30:45.346274Z","caller":"traceutil/trace.go:171","msg":"trace[355545967] transaction","detail":"{read_only:false; response_revision:24980; number_of_response:1; }","duration":"146.85094ms","start":"2026-05-27T20:30:45.199399Z","end":"2026-05-27T20:30:45.34625Z","steps":["trace[355545967] 'process raft request' (duration: 77.975471ms)","trace[355545967] 'compare' (duration: 68.774028ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:30:45.780994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.767241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:30:45.781098Z","caller":"traceutil/trace.go:171","msg":"trace[1333719051] linearizableReadLoop","detail":"{readStateIndex:25790; appliedIndex:25789; }","duration":"243.950006ms","start":"2026-05-27T20:30:45.53713Z","end":"2026-05-27T20:30:45.78108Z","steps":["trace[1333719051] 'read index received' (duration: 88.89153ms)","trace[1333719051] 'applied index is now lower than readState.Index' (duration: 155.057146ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:30:45.78124Z","caller":"traceutil/trace.go:171","msg":"trace[1822020395] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"291.00685ms","start":"2026-05-27T20:30:45.490198Z","end":"2026-05-27T20:30:45.781205Z","steps":["trace[1822020395] 'process raft request' (duration: 135.909713ms)","trace[1822020395] 'compare' (duration: 154.642118ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:30:45.781314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.155459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:30:45.781377Z","caller":"traceutil/trace.go:171","msg":"trace[744774747] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:24982; }","duration":"244.281692ms","start":"2026-05-27T20:30:45.537083Z","end":"2026-05-27T20:30:45.781364Z","steps":["trace[744774747] 'agreement among raft nodes before linearized reading' (duration: 244.143059ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:30:45.997415Z","caller":"traceutil/trace.go:171","msg":"trace[1036194660] transaction","detail":"{read_only:false; response_revision:24983; number_of_response:1; }","duration":"361.546229ms","start":"2026-05-27T20:30:45.635844Z","end":"2026-05-27T20:30:45.99739Z","steps":["trace[1036194660] 'process raft request' (duration: 350.378777ms)","trace[1036194660] 'compare' (duration: 10.982039ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:30:45.997688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:45.635819Z","time spent":"361.768624ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:30:45.998955Z","caller":"traceutil/trace.go:171","msg":"trace[563695069] linearizableReadLoop","detail":"{readStateIndex:25792; appliedIndex:25790; }","duration":"217.777978ms","start":"2026-05-27T20:30:45.781155Z","end":"2026-05-27T20:30:45.998933Z","steps":["trace[563695069] 'read index received' (duration: 205.048586ms)","trace[563695069] 'applied index is now lower than readState.Index' (duration: 12.728462ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:30:45.99914Z","caller":"traceutil/trace.go:171","msg":"trace[766636500] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"359.623163ms","start":"2026-05-27T20:30:45.639483Z","end":"2026-05-27T20:30:45.999106Z","steps":["trace[766636500] 'process raft request' (duration: 359.297287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:45.999298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.361043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-27T20:30:45.999364Z","caller":"traceutil/trace.go:171","msg":"trace[1364870941] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24984; }","duration":"304.468736ms","start":"2026-05-27T20:30:45.694874Z","end":"2026-05-27T20:30:45.999342Z","steps":["trace[1364870941] 'agreement among raft nodes before linearized reading' (duration: 304.203561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:45.999373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.103148ms","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":"warn","ts":"2026-05-27T20:30:45.999311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:45.639453Z","time spent":"359.760856ms","remote":"127.0.0.1:51044","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-27T20:30:45.999479Z","caller":"traceutil/trace.go:171","msg":"trace[605088855] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24984; }","duration":"304.254782ms","start":"2026-05-27T20:30:45.695201Z","end":"2026-05-27T20:30:45.999456Z","steps":["trace[605088855] 'agreement among raft nodes before linearized reading' (duration: 303.856264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:45.999529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:45.695196Z","time spent":"304.325793ms","remote":"127.0.0.1:51044","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-27T20:30:45.999422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:45.694849Z","time spent":"304.555827ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-27T20:30:45.999743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.949424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-27T20:30:45.999633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.955385ms","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-27T20:30:45.999825Z","caller":"traceutil/trace.go:171","msg":"trace[241263334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24984; }","duration":"305.160308ms","start":"2026-05-27T20:30:45.694645Z","end":"2026-05-27T20:30:45.999805Z","steps":["trace[241263334] 'agreement among raft nodes before linearized reading' (duration: 304.872843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:45.999862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:45.69464Z","time spent":"305.213169ms","remote":"127.0.0.1:51044","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-27T20:30:45.999625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.162925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T20:30:45.999957Z","caller":"traceutil/trace.go:171","msg":"trace[69755524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24984; }","duration":"308.549453ms","start":"2026-05-27T20:30:45.691391Z","end":"2026-05-27T20:30:45.99994Z","steps":["trace[69755524] 'agreement among raft nodes before linearized reading' (duration: 308.022833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:46.000009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:45.691371Z","time spent":"308.622984ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T20:30:45.999773Z","caller":"traceutil/trace.go:171","msg":"trace[1456838794] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24984; }","duration":"304.021456ms","start":"2026-05-27T20:30:45.695745Z","end":"2026-05-27T20:30:45.999766Z","steps":["trace[1456838794] 'agreement among raft nodes before linearized reading' (duration: 303.898103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:46.000153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:45.695733Z","time spent":"304.414164ms","remote":"127.0.0.1:51044","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":"warn","ts":"2026-05-27T20:30:46.000344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.980193ms","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-27T20:30:46.000403Z","caller":"traceutil/trace.go:171","msg":"trace[1222645783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24984; }","duration":"306.107756ms","start":"2026-05-27T20:30:45.694281Z","end":"2026-05-27T20:30:46.000389Z","steps":["trace[1222645783] 'agreement among raft nodes before linearized reading' (duration: 305.25524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:30:46.000441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:30:45.694264Z","time spent":"306.167057ms","remote":"127.0.0.1:51044","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-27T20:30:59.773731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.315105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T20:30:59.773864Z","caller":"traceutil/trace.go:171","msg":"trace[262485589] transaction","detail":"{read_only:false; response_revision:25093; number_of_response:1; }","duration":"216.28897ms","start":"2026-05-27T20:30:59.557548Z","end":"2026-05-27T20:30:59.773837Z","steps":["trace[262485589] 'process raft request' (duration: 83.746551ms)","trace[262485589] 'compare' (duration: 131.908727ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:31:00.008565Z","caller":"traceutil/trace.go:171","msg":"trace[167553320] linearizableReadLoop","detail":"{readStateIndex:25906; appliedIndex:25905; }","duration":"113.397645ms","start":"2026-05-27T20:30:59.895147Z","end":"2026-05-27T20:31:00.008544Z","steps":["trace[167553320] 'read index received' (duration: 113.229962ms)","trace[167553320] 'applied index is now lower than readState.Index' (duration: 166.863µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:31:00.008655Z","caller":"traceutil/trace.go:171","msg":"trace[882279229] transaction","detail":"{read_only:false; response_revision:25094; number_of_response:1; }","duration":"173.597808ms","start":"2026-05-27T20:30:59.835027Z","end":"2026-05-27T20:31:00.008625Z","steps":["trace[882279229] 'process raft request' (duration: 173.402865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:31:00.008879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.716922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-05-27T20:31:00.008988Z","caller":"traceutil/trace.go:171","msg":"trace[1552878437] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:25094; }","duration":"113.872924ms","start":"2026-05-27T20:30:59.895088Z","end":"2026-05-27T20:31:00.008961Z","steps":["trace[1552878437] 'agreement among raft nodes before linearized reading' (duration: 113.590658ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:31:00.24805Z","caller":"traceutil/trace.go:171","msg":"trace[2140135883] transaction","detail":"{read_only:false; response_revision:25095; number_of_response:1; }","duration":"204.315963ms","start":"2026-05-27T20:31:00.043703Z","end":"2026-05-27T20:31:00.248019Z","steps":["trace[2140135883] 'process raft request' (duration: 132.644952ms)","trace[2140135883] 'compare' (duration: 71.423156ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:31:00.248163Z","caller":"traceutil/trace.go:171","msg":"trace[1841105967] linearizableReadLoop","detail":"{readStateIndex:25908; appliedIndex:25906; }","duration":"177.767508ms","start":"2026-05-27T20:31:00.070344Z","end":"2026-05-27T20:31:00.248111Z","steps":["trace[1841105967] 'read index received' (duration: 106.014115ms)","trace[1841105967] 'applied index is now lower than readState.Index' (duration: 71.751363ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:31:00.248234Z","caller":"traceutil/trace.go:171","msg":"trace[1785388675] transaction","detail":"{read_only:false; response_revision:25096; number_of_response:1; }","duration":"200.55191ms","start":"2026-05-27T20:31:00.047647Z","end":"2026-05-27T20:31:00.248199Z","steps":["trace[1785388675] 'process raft request' (duration: 200.306666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:31:00.248386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.045073ms","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-27T20:31:00.248737Z","caller":"traceutil/trace.go:171","msg":"trace[330253730] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25096; }","duration":"178.44718ms","start":"2026-05-27T20:31:00.070271Z","end":"2026-05-27T20:31:00.248718Z","steps":["trace[330253730] 'agreement among raft nodes before linearized reading' (duration: 177.957551ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:31:00.319636Z","caller":"traceutil/trace.go:171","msg":"trace[217426664] transaction","detail":"{read_only:false; response_revision:25097; number_of_response:1; }","duration":"105.796991ms","start":"2026-05-27T20:31:00.213798Z","end":"2026-05-27T20:31:00.319595Z","steps":["trace[217426664] 'process raft request' (duration: 105.563397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:31:00.319703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.508027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-27T20:31:00.319731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.926217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:31:00.319764Z","caller":"traceutil/trace.go:171","msg":"trace[1746122745] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25097; }","duration":"244.605448ms","start":"2026-05-27T20:31:00.075143Z","end":"2026-05-27T20:31:00.319748Z","steps":["trace[1746122745] 'agreement among raft nodes before linearized reading' (duration: 244.370964ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:31:00.319791Z","caller":"traceutil/trace.go:171","msg":"trace[1679933136] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:25097; }","duration":"243.035339ms","start":"2026-05-27T20:31:00.076738Z","end":"2026-05-27T20:31:00.319773Z","steps":["trace[1679933136] 'agreement among raft nodes before linearized reading' (duration: 242.944208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:31:00.319856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.192317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-05-27T20:31:00.319867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.553087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T20:31:00.319927Z","caller":"traceutil/trace.go:171","msg":"trace[1332621835] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25097; }","duration":"239.270998ms","start":"2026-05-27T20:31:00.080637Z","end":"2026-05-27T20:31:00.319908Z","steps":["trace[1332621835] 'agreement among raft nodes before linearized reading' (duration: 239.105095ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:31:00.319952Z","caller":"traceutil/trace.go:171","msg":"trace[2043071497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25097; }","duration":"244.69526ms","start":"2026-05-27T20:31:00.075239Z","end":"2026-05-27T20:31:00.319934Z","steps":["trace[2043071497] 'agreement among raft nodes before linearized reading' (duration: 244.424365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:31:00.3201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.601429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-27T20:31:00.320209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.587144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-27T20:31:00.320288Z","caller":"traceutil/trace.go:171","msg":"trace[1015696212] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25097; }","duration":"239.678086ms","start":"2026-05-27T20:31:00.080593Z","end":"2026-05-27T20:31:00.320271Z","steps":["trace[1015696212] 'agreement among raft nodes before linearized reading' (duration: 238.935072ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:31:00.320262Z","caller":"traceutil/trace.go:171","msg":"trace[1787619257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25097; }","duration":"244.809972ms","start":"2026-05-27T20:31:00.075422Z","end":"2026-05-27T20:31:00.320232Z","steps":["trace[1787619257] 'agreement among raft nodes before linearized reading' (duration: 244.208911ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:31:00.377824Z","caller":"traceutil/trace.go:171","msg":"trace[1485541106] transaction","detail":"{read_only:false; response_revision:25098; number_of_response:1; }","duration":"125.404674ms","start":"2026-05-27T20:31:00.252382Z","end":"2026-05-27T20:31:00.377787Z","steps":["trace[1485541106] 'process raft request' (duration: 97.252348ms)","trace[1485541106] 'compare' (duration: 27.993312ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:31:00.443181Z","caller":"traceutil/trace.go:171","msg":"trace[1079558923] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"118.731976ms","start":"2026-05-27T20:31:00.324416Z","end":"2026-05-27T20:31:00.443148Z","steps":["trace[1079558923] 'process raft request' (duration: 118.40297ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:31:00.443322Z","caller":"traceutil/trace.go:171","msg":"trace[1384333624] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"115.933482ms","start":"2026-05-27T20:31:00.327362Z","end":"2026-05-27T20:31:00.443296Z","steps":["trace[1384333624] 'process raft request' (duration: 115.892021ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:31:00.443359Z","caller":"traceutil/trace.go:171","msg":"trace[1515260681] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"117.125926ms","start":"2026-05-27T20:31:00.326201Z","end":"2026-05-27T20:31:00.443327Z","steps":["trace[1515260681] 'process raft request' (duration: 116.935032ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:31:00.443462Z","caller":"traceutil/trace.go:171","msg":"trace[782828473] transaction","detail":"{read_only:false; response_revision:25102; number_of_response:1; }","duration":"116.29832ms","start":"2026-05-27T20:31:00.327156Z","end":"2026-05-27T20:31:00.443454Z","steps":["trace[782828473] 'process raft request' (duration: 116.060816ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:31:00.44354Z","caller":"traceutil/trace.go:171","msg":"trace[619542756] transaction","detail":"{read_only:false; response_revision:25100; number_of_response:1; }","duration":"118.675684ms","start":"2026-05-27T20:31:00.32484Z","end":"2026-05-27T20:31:00.443515Z","steps":["trace[619542756] 'process raft request' (duration: 118.193975ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:31:10.555649Z","caller":"traceutil/trace.go:171","msg":"trace[100885909] transaction","detail":"{read_only:false; response_revision:25171; number_of_response:1; }","duration":"265.616567ms","start":"2026-05-27T20:31:10.290011Z","end":"2026-05-27T20:31:10.555628Z","steps":["trace[100885909] 'process raft request' (duration: 265.474984ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:31:10.809887Z","caller":"traceutil/trace.go:171","msg":"trace[989540883] linearizableReadLoop","detail":"{readStateIndex:25986; appliedIndex:25984; }","duration":"355.818571ms","start":"2026-05-27T20:31:10.453925Z","end":"2026-05-27T20:31:10.809743Z","steps":["trace[989540883] 'read index received' (duration: 101.650762ms)","trace[989540883] 'applied index is now lower than readState.Index' (duration: 254.167019ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:31:10.809969Z","caller":"traceutil/trace.go:171","msg":"trace[727381785] transaction","detail":"{read_only:false; response_revision:25172; number_of_response:1; }","duration":"517.966202ms","start":"2026-05-27T20:31:10.291827Z","end":"2026-05-27T20:31:10.809793Z","steps":["trace[727381785] 'process raft request' (duration: 447.625225ms)","trace[727381785] 'compare' (duration: 69.936749ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:31:10.810014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.083407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-27T20:31:10.810055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:31:10.291807Z","time spent":"518.179876ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:31:10.810082Z","caller":"traceutil/trace.go:171","msg":"trace[1148821633] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25172; }","duration":"356.191249ms","start":"2026-05-27T20:31:10.453876Z","end":"2026-05-27T20:31:10.810067Z","steps":["trace[1148821633] 'agreement among raft nodes before linearized reading' (duration: 356.000744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:31:10.810114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:31:10.453856Z","time spent":"356.25053ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T20:31:10.824329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.485757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-05-27T20:31:10.824363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.426224ms","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-27T20:31:10.824408Z","caller":"traceutil/trace.go:171","msg":"trace[1490192018] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25173; }","duration":"333.588868ms","start":"2026-05-27T20:31:10.490801Z","end":"2026-05-27T20:31:10.82439Z","steps":["trace[1490192018] 'agreement among raft nodes before linearized reading' (duration: 333.366804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:31:10.824408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.556426ms","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-27T20:31:10.824482Z","caller":"traceutil/trace.go:171","msg":"trace[104706082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25173; }","duration":"335.572086ms","start":"2026-05-27T20:31:10.488893Z","end":"2026-05-27T20:31:10.824466Z","steps":["trace[104706082] 'agreement among raft nodes before linearized reading' (duration: 335.381162ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:31:10.8245Z","caller":"traceutil/trace.go:171","msg":"trace[724925901] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25173; }","duration":"335.666928ms","start":"2026-05-27T20:31:10.488807Z","end":"2026-05-27T20:31:10.824474Z","steps":["trace[724925901] 'agreement among raft nodes before linearized reading' (duration: 335.384663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:31:10.824549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.932534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:31:10.82437Z","caller":"traceutil/trace.go:171","msg":"trace[1316269689] transaction","detail":"{read_only:false; response_revision:25173; number_of_response:1; }","duration":"174.511576ms","start":"2026-05-27T20:31:10.649813Z","end":"2026-05-27T20:31:10.824325Z","steps":["trace[1316269689] 'process raft request' (duration: 174.135408ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:31:10.824604Z","caller":"traceutil/trace.go:171","msg":"trace[406830345] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25173; }","duration":"295.022616ms","start":"2026-05-27T20:31:10.529566Z","end":"2026-05-27T20:31:10.824589Z","steps":["trace[406830345] 'agreement among raft nodes before linearized reading' (duration: 294.941305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:31:10.8246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.651727ms","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-27T20:31:10.82463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:31:10.488802Z","time spent":"335.739059ms","remote":"127.0.0.1:51044","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-27T20:31:10.82469Z","caller":"traceutil/trace.go:171","msg":"trace[1860157075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25173; }","duration":"336.80421ms","start":"2026-05-27T20:31:10.487868Z","end":"2026-05-27T20:31:10.824672Z","steps":["trace[1860157075] 'agreement among raft nodes before linearized reading' (duration: 336.380762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:31:10.824477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:31:10.490793Z","time spent":"333.650389ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-27T20:31:10.824738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:31:10.487854Z","time spent":"336.875081ms","remote":"127.0.0.1:51044","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-27T20:31:10.824531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:31:10.488881Z","time spent":"335.641338ms","remote":"127.0.0.1:51044","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-27T20:31:10.824441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.685906ms","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-27T20:31:10.825244Z","caller":"traceutil/trace.go:171","msg":"trace[292296586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25173; }","duration":"338.742076ms","start":"2026-05-27T20:31:10.486487Z","end":"2026-05-27T20:31:10.825229Z","steps":["trace[292296586] 'agreement among raft nodes before linearized reading' (duration: 337.616465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:31:10.825408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:31:10.486459Z","time spent":"338.93616ms","remote":"127.0.0.1:51044","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":"info","ts":"2026-05-27T20:31:11.190409Z","caller":"traceutil/trace.go:171","msg":"trace[944990117] transaction","detail":"{read_only:false; response_revision:25175; number_of_response:1; }","duration":"361.284545ms","start":"2026-05-27T20:31:10.829098Z","end":"2026-05-27T20:31:11.190382Z","steps":["trace[944990117] 'process raft request' (duration: 281.353357ms)","trace[944990117] 'compare' (duration: 79.628243ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:31:11.190571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:31:10.829078Z","time spent":"361.391837ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:31:11.190636Z","caller":"traceutil/trace.go:171","msg":"trace[1771505817] transaction","detail":"{read_only:false; response_revision:25181; number_of_response:1; }","duration":"157.840229ms","start":"2026-05-27T20:31:11.032775Z","end":"2026-05-27T20:31:11.190615Z","steps":["trace[1771505817] 'process raft request' (duration: 157.798308ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:31:11.190805Z","caller":"traceutil/trace.go:171","msg":"trace[154634709] transaction","detail":"{read_only:false; response_revision:25178; number_of_response:1; }","duration":"361.173492ms","start":"2026-05-27T20:31:10.829601Z","end":"2026-05-27T20:31:11.190775Z","steps":["trace[154634709] 'process raft request' (duration: 360.826165ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:31:11.191013Z","caller":"traceutil/trace.go:171","msg":"trace[142194742] transaction","detail":"{read_only:false; response_revision:25180; number_of_response:1; }","duration":"226.870361ms","start":"2026-05-27T20:31:10.964127Z","end":"2026-05-27T20:31:11.190997Z","steps":["trace[142194742] 'process raft request' (duration: 226.387761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:31:11.191039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:31:10.829579Z","time spent":"361.322905ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:31:11.191044Z","caller":"traceutil/trace.go:171","msg":"trace[826053567] transaction","detail":"{read_only:false; response_revision:25179; number_of_response:1; }","duration":"361.171302ms","start":"2026-05-27T20:31:10.829853Z","end":"2026-05-27T20:31:11.191024Z","steps":["trace[826053567] 'process raft request' (duration: 360.621551ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:31:11.191016Z","caller":"traceutil/trace.go:171","msg":"trace[1195999754] linearizableReadLoop","detail":"{readStateIndex:25993; appliedIndex:25988; }","duration":"280.840517ms","start":"2026-05-27T20:31:10.910139Z","end":"2026-05-27T20:31:11.190979Z","steps":["trace[1195999754] 'read index received' (duration: 200.321257ms)","trace[1195999754] 'applied index is now lower than readState.Index' (duration: 80.51561ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:31:11.191073Z","caller":"traceutil/trace.go:171","msg":"trace[838911293] transaction","detail":"{read_only:false; response_revision:25177; number_of_response:1; }","duration":"361.425538ms","start":"2026-05-27T20:31:10.829569Z","end":"2026-05-27T20:31:11.190995Z","steps":["trace[838911293] 'process raft request' (duration: 360.813395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:31:11.191141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:31:10.829842Z","time spent":"361.246144ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:31:11.19115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.00628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:31:11.191191Z","caller":"traceutil/trace.go:171","msg":"trace[1850729878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25181; }","duration":"281.082231ms","start":"2026-05-27T20:31:10.910096Z","end":"2026-05-27T20:31:11.191179Z","steps":["trace[1850729878] 'agreement among raft nodes before linearized reading' (duration: 280.974479ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:31:11.191171Z","caller":"traceutil/trace.go:171","msg":"trace[1764473249] transaction","detail":"{read_only:false; response_revision:25176; number_of_response:1; }","duration":"361.689143ms","start":"2026-05-27T20:31:10.829448Z","end":"2026-05-27T20:31:11.191137Z","steps":["trace[1764473249] 'process raft request' (duration: 360.831836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:31:11.191236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:31:10.829534Z","time spent":"361.619682ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:31:11.191288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.79436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-27T20:31:11.191323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:31:10.829423Z","time spent":"361.817825ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:31:11.191355Z","caller":"traceutil/trace.go:171","msg":"trace[675185451] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:25181; }","duration":"276.871391ms","start":"2026-05-27T20:31:10.914454Z","end":"2026-05-27T20:31:11.191326Z","steps":["trace[675185451] 'agreement among raft nodes before linearized reading' (duration: 276.762839ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:32:54.222901Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069a6.wal"} {"level":"info","ts":"2026-05-27T20:32:58.496416Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23372} {"level":"info","ts":"2026-05-27T20:32:58.618355Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23372,"took":"118.682365ms","hash":2565930041,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":36290560,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-27T20:32:58.618412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2565930041,"revision":23372,"compact-revision":20152} {"level":"info","ts":"2026-05-27T20:37:58.502677Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26253} {"level":"info","ts":"2026-05-27T20:37:58.647715Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26253,"took":"139.878246ms","hash":2853399189,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":33505280,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-27T20:37:58.647801Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2853399189,"revision":26253,"compact-revision":23372} {"level":"info","ts":"2026-05-27T20:38:32.489897Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6e120e57916b9bd4","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-27T20:38:32.4961Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-27T20:38:32.496216Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-27T20:42:58.510012Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28833} {"level":"info","ts":"2026-05-27T20:42:58.645341Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28833,"took":"128.297487ms","hash":3558242691,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":30240768,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-27T20:42:58.645422Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3558242691,"revision":28833,"compact-revision":26253} {"level":"info","ts":"2026-05-27T20:47:58.516315Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31052} {"level":"info","ts":"2026-05-27T20:47:58.637422Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31052,"took":"117.626082ms","hash":2898400383,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":30322688,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-27T20:47:58.637518Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2898400383,"revision":31052,"compact-revision":28833} {"level":"info","ts":"2026-05-27T20:52:58.524835Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33322} {"level":"info","ts":"2026-05-27T20:52:58.677809Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33322,"took":"142.516485ms","hash":1804823576,"current-db-size-bytes":59097088,"current-db-size":"59 MB","current-db-size-in-use-bytes":32120832,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-27T20:52:58.677876Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1804823576,"revision":33322,"compact-revision":31052} {"level":"info","ts":"2026-05-27T20:57:58.53145Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35616} {"level":"info","ts":"2026-05-27T20:57:58.667821Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35616,"took":"132.162948ms","hash":3217689551,"current-db-size-bytes":59568128,"current-db-size":"60 MB","current-db-size-in-use-bytes":31158272,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-27T20:57:58.667886Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3217689551,"revision":35616,"compact-revision":33322} {"level":"info","ts":"2026-05-27T21:00:00.129747Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6e120e57916b9bd4","local-member-applied-index":40005,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-27T21:00:00.134966Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-05-27T21:00:00.135103Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005}