{"level":"warn","ts":"2026-07-17T18:07:57.694573Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T18:07:57.69466Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-17T18:07:57.694735Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T18:07:57.694751Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-07-17T18:07:57.694772Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-17T18:07:57.695421Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-07-17T18:07:57.695516Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-17T18:07:57.697457Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.752099ms"} {"level":"info","ts":"2026-07-17T18:07:57.701441Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-07-17T18:07:57.701515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T18:07:57.70154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-07-17T18:07:57.701553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T18:07:57.701565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-07-17T18:07:57.701605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-07-17T18:07:57.706869Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T18:07:57.708437Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T18:07:57.709651Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-17T18:07:57.711798Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T18:07:57.711991Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T18:07:57.711908Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T18:07:57.712047Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T18:07:57.712058Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T18:07:57.712963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-07-17T18:07:57.713201Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-07-17T18:07:57.716846Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-17T18:07:57.716968Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-07-17T18:07:57.717034Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-07-17T18:07:57.717353Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T18:07:57.717433Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T18:07:58.202086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T18:07:58.202159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T18:07:58.20221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-07-17T18:07:58.202232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-07-17T18:07:58.202243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-07-17T18:07:58.202258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-07-17T18:07:58.202274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-07-17T18:07:58.203856Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T18:07:58.204306Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T18:07:58.204312Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T18:07:58.204537Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T18:07:58.204667Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T18:07:58.204725Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T18:07:58.204798Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T18:07:58.204904Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T18:07:58.20495Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T18:07:58.208878Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-07-17T18:07:58.211178Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T18:08:25.300106Z","caller":"traceutil/trace.go:171","msg":"trace[1747419919] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"161.463077ms","start":"2026-07-17T18:08:25.138617Z","end":"2026-07-17T18:08:25.30008Z","steps":["trace[1747419919] 'process raft request' (duration: 161.23366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:41.003231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.876464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T18:08:41.003393Z","caller":"traceutil/trace.go:171","msg":"trace[670769948] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"223.994253ms","start":"2026-07-17T18:08:40.779375Z","end":"2026-07-17T18:08:41.00337Z","steps":["trace[670769948] 'process raft request' (duration: 92.219804ms)","trace[670769948] 'compare' (duration: 130.71883ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:08:41.516112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.40758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T18:08:41.516178Z","caller":"traceutil/trace.go:171","msg":"trace[1360927511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"140.542793ms","start":"2026-07-17T18:08:41.375621Z","end":"2026-07-17T18:08:41.516164Z","steps":["trace[1360927511] 'range keys from in-memory index tree' (duration: 140.310917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:41.516313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.87432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kqfcf\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-17T18:08:41.516374Z","caller":"traceutil/trace.go:171","msg":"trace[939256632] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kqfcf; range_end:; response_count:1; response_revision:591; }","duration":"352.985393ms","start":"2026-07-17T18:08:41.163376Z","end":"2026-07-17T18:08:41.516361Z","steps":["trace[939256632] 'range keys from in-memory index tree' (duration: 352.777657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:41.516402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:41.163356Z","time spent":"353.039574ms","remote":"127.0.0.1:46334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-kqfcf\" "} {"level":"warn","ts":"2026-07-17T18:08:41.516306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.135496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-17T18:08:41.516463Z","caller":"traceutil/trace.go:171","msg":"trace[1241672961] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:591; }","duration":"368.351372ms","start":"2026-07-17T18:08:41.148102Z","end":"2026-07-17T18:08:41.516454Z","steps":["trace[1241672961] 'range keys from in-memory index tree' (duration: 368.005753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:41.516493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:41.148075Z","time spent":"368.408353ms","remote":"127.0.0.1:46200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.205\" "} {"level":"info","ts":"2026-07-17T18:08:41.529965Z","caller":"traceutil/trace.go:171","msg":"trace[120122358] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"123.530488ms","start":"2026-07-17T18:08:41.40641Z","end":"2026-07-17T18:08:41.529941Z","steps":["trace[120122358] 'process raft request' (duration: 122.864619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:56.447838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.328216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-58rzw\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-17T18:08:56.447946Z","caller":"traceutil/trace.go:171","msg":"trace[360090584] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-58rzw; range_end:; response_count:1; response_revision:750; }","duration":"198.480551ms","start":"2026-07-17T18:08:56.249446Z","end":"2026-07-17T18:08:56.447927Z","steps":["trace[360090584] 'range keys from in-memory index tree' (duration: 198.153261ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:19.337952Z","caller":"traceutil/trace.go:171","msg":"trace[613530924] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"226.232332ms","start":"2026-07-17T18:10:19.111691Z","end":"2026-07-17T18:10:19.337923Z","steps":["trace[613530924] 'process raft request' (duration: 226.044756ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:19.441182Z","caller":"traceutil/trace.go:171","msg":"trace[1642199358] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1928; }","duration":"101.871119ms","start":"2026-07-17T18:10:19.339276Z","end":"2026-07-17T18:10:19.441147Z","steps":["trace[1642199358] 'read index received' (duration: 74.479027ms)","trace[1642199358] 'applied index is now lower than readState.Index' (duration: 27.391072ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:10:19.441234Z","caller":"traceutil/trace.go:171","msg":"trace[373982670] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"309.149208ms","start":"2026-07-17T18:10:19.132023Z","end":"2026-07-17T18:10:19.441172Z","steps":["trace[373982670] 'process raft request' (duration: 281.798857ms)","trace[373982670] 'compare' (duration: 27.209217ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:10:19.441569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.24969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:10:19.441713Z","caller":"traceutil/trace.go:171","msg":"trace[439329408] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1857; }","duration":"102.420695ms","start":"2026-07-17T18:10:19.339267Z","end":"2026-07-17T18:10:19.441688Z","steps":["trace[439329408] 'agreement among raft nodes before linearized reading' (duration: 102.050045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:19.441755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:19.132009Z","time spent":"309.335983ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:10:19.781024Z","caller":"traceutil/trace.go:171","msg":"trace[2079434003] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"133.422549ms","start":"2026-07-17T18:10:19.64756Z","end":"2026-07-17T18:10:19.780983Z","steps":["trace[2079434003] 'read index received' (duration: 127.035329ms)","trace[2079434003] 'applied index is now lower than readState.Index' (duration: 6.38587ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:10:19.781148Z","caller":"traceutil/trace.go:171","msg":"trace[1587965964] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"156.460207ms","start":"2026-07-17T18:10:19.624641Z","end":"2026-07-17T18:10:19.781101Z","steps":["trace[1587965964] 'process raft request' (duration: 149.929763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:19.781385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.792019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-681a8b9b-a5f8-4f87-b8fc-71b9b8389ae2\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-17T18:10:19.781497Z","caller":"traceutil/trace.go:171","msg":"trace[1111782861] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-681a8b9b-a5f8-4f87-b8fc-71b9b8389ae2; range_end:; response_count:1; response_revision:1858; }","duration":"133.967783ms","start":"2026-07-17T18:10:19.647503Z","end":"2026-07-17T18:10:19.781471Z","steps":["trace[1111782861] 'agreement among raft nodes before linearized reading' (duration: 133.606843ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:33.099181Z","caller":"traceutil/trace.go:171","msg":"trace[1340945276] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"242.524692ms","start":"2026-07-17T18:10:32.856634Z","end":"2026-07-17T18:10:33.099158Z","steps":["trace[1340945276] 'process raft request' (duration: 242.46755ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:33.099193Z","caller":"traceutil/trace.go:171","msg":"trace[1546933073] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"244.873408ms","start":"2026-07-17T18:10:32.854271Z","end":"2026-07-17T18:10:33.099145Z","steps":["trace[1546933073] 'process raft request' (duration: 235.607987ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:33.09918Z","caller":"traceutil/trace.go:171","msg":"trace[747330776] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"244.868508ms","start":"2026-07-17T18:10:32.854287Z","end":"2026-07-17T18:10:33.099156Z","steps":["trace[747330776] 'process raft request' (duration: 244.709173ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:33.110133Z","caller":"traceutil/trace.go:171","msg":"trace[214175274] linearizableReadLoop","detail":"{readStateIndex:2052; appliedIndex:2048; }","duration":"138.847801ms","start":"2026-07-17T18:10:32.971264Z","end":"2026-07-17T18:10:33.110111Z","steps":["trace[214175274] 'read index received' (duration: 118.636221ms)","trace[214175274] 'applied index is now lower than readState.Index' (duration: 20.210359ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:10:33.110224Z","caller":"traceutil/trace.go:171","msg":"trace[1899575315] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"152.906747ms","start":"2026-07-17T18:10:32.957305Z","end":"2026-07-17T18:10:33.110211Z","steps":["trace[1899575315] 'process raft request' (duration: 152.707152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:33.110322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.039367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-17T18:10:33.110366Z","caller":"traceutil/trace.go:171","msg":"trace[1997796960] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1977; }","duration":"139.126008ms","start":"2026-07-17T18:10:32.971226Z","end":"2026-07-17T18:10:33.110352Z","steps":["trace[1997796960] 'agreement among raft nodes before linearized reading' (duration: 138.959204ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:36.008153Z","caller":"traceutil/trace.go:171","msg":"trace[70309421] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"257.35102ms","start":"2026-07-17T18:10:35.750777Z","end":"2026-07-17T18:10:36.008128Z","steps":["trace[70309421] 'process raft request' (duration: 257.213676ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:36.03124Z","caller":"traceutil/trace.go:171","msg":"trace[118381615] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"174.175256ms","start":"2026-07-17T18:10:35.85704Z","end":"2026-07-17T18:10:36.031215Z","steps":["trace[118381615] 'process raft request' (duration: 174.043052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:11:15.56042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.924214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T18:11:15.560512Z","caller":"traceutil/trace.go:171","msg":"trace[1591506711] linearizableReadLoop","detail":"{readStateIndex:2295; appliedIndex:2294; }","duration":"264.928733ms","start":"2026-07-17T18:11:15.29557Z","end":"2026-07-17T18:11:15.560498Z","steps":["trace[1591506711] 'read index received' (duration: 142.850214ms)","trace[1591506711] 'applied index is now lower than readState.Index' (duration: 122.077429ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:11:15.560573Z","caller":"traceutil/trace.go:171","msg":"trace[739327579] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"285.03866ms","start":"2026-07-17T18:11:15.275526Z","end":"2026-07-17T18:11:15.560565Z","steps":["trace[739327579] 'process raft request' (duration: 162.896329ms)","trace[739327579] 'compare' (duration: 121.76266ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:11:15.560775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.163149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T18:11:15.560857Z","caller":"traceutil/trace.go:171","msg":"trace[1957749834] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2211; }","duration":"265.300314ms","start":"2026-07-17T18:11:15.295539Z","end":"2026-07-17T18:11:15.560839Z","steps":["trace[1957749834] 'agreement among raft nodes before linearized reading' (duration: 265.140709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:11:15.561014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.739401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-17T18:11:15.561585Z","caller":"traceutil/trace.go:171","msg":"trace[2138750368] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2211; }","duration":"165.328988ms","start":"2026-07-17T18:11:15.396219Z","end":"2026-07-17T18:11:15.561548Z","steps":["trace[2138750368] 'agreement among raft nodes before linearized reading' (duration: 164.496054ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:11:15.780648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.273961ms","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-07-17T18:11:15.780674Z","caller":"traceutil/trace.go:171","msg":"trace[1481835607] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"112.012906ms","start":"2026-07-17T18:11:15.66863Z","end":"2026-07-17T18:11:15.780643Z","steps":["trace[1481835607] 'process raft request' (duration: 110.347129ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:11:15.780764Z","caller":"traceutil/trace.go:171","msg":"trace[551710669] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2211; }","duration":"113.467316ms","start":"2026-07-17T18:11:15.667275Z","end":"2026-07-17T18:11:15.780742Z","steps":["trace[551710669] 'range keys from in-memory index tree' (duration: 113.076296ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:12:30.153687Z","caller":"traceutil/trace.go:171","msg":"trace[223930662] transaction","detail":"{read_only:false; response_revision:2809; number_of_response:1; }","duration":"102.909841ms","start":"2026-07-17T18:12:30.050737Z","end":"2026-07-17T18:12:30.153647Z","steps":["trace[223930662] 'process raft request' (duration: 80.453382ms)","trace[223930662] 'compare' (duration: 22.201802ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:12:30.339261Z","caller":"traceutil/trace.go:171","msg":"trace[596687523] transaction","detail":"{read_only:false; response_revision:2810; number_of_response:1; }","duration":"141.057058ms","start":"2026-07-17T18:12:30.198176Z","end":"2026-07-17T18:12:30.339233Z","steps":["trace[596687523] 'process raft request' (duration: 130.918374ms)","trace[596687523] 'compare' (duration: 10.028851ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:14:55.386796Z","caller":"traceutil/trace.go:171","msg":"trace[354734085] transaction","detail":"{read_only:false; response_revision:3777; number_of_response:1; }","duration":"114.149204ms","start":"2026-07-17T18:14:55.272617Z","end":"2026-07-17T18:14:55.386766Z","steps":["trace[354734085] 'process raft request' (duration: 113.872956ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:16:14.012948Z","caller":"traceutil/trace.go:171","msg":"trace[651441829] linearizableReadLoop","detail":"{readStateIndex:5013; appliedIndex:5012; }","duration":"158.218996ms","start":"2026-07-17T18:16:13.854705Z","end":"2026-07-17T18:16:14.012924Z","steps":["trace[651441829] 'read index received' (duration: 157.974259ms)","trace[651441829] 'applied index is now lower than readState.Index' (duration: 244.017µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:16:14.013048Z","caller":"traceutil/trace.go:171","msg":"trace[1427783245] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"159.635154ms","start":"2026-07-17T18:16:13.853384Z","end":"2026-07-17T18:16:14.013019Z","steps":["trace[1427783245] 'process raft request' (duration: 159.347376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:16:14.013122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.39015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/loki-alerting-rules\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:16:14.013159Z","caller":"traceutil/trace.go:171","msg":"trace[864153877] range","detail":"{range_begin:/registry/configmaps/monitoring/loki-alerting-rules; range_end:; response_count:0; response_revision:4830; }","duration":"158.466592ms","start":"2026-07-17T18:16:13.854681Z","end":"2026-07-17T18:16:14.013147Z","steps":["trace[864153877] 'agreement among raft nodes before linearized reading' (duration: 158.38153ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:16:14.132769Z","caller":"traceutil/trace.go:171","msg":"trace[879487539] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"113.206865ms","start":"2026-07-17T18:16:14.019544Z","end":"2026-07-17T18:16:14.13275Z","steps":["trace[879487539] 'process raft request' (duration: 113.04582ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:16:14.132873Z","caller":"traceutil/trace.go:171","msg":"trace[1644723571] linearizableReadLoop","detail":"{readStateIndex:5016; appliedIndex:5014; }","duration":"111.602551ms","start":"2026-07-17T18:16:14.021243Z","end":"2026-07-17T18:16:14.132846Z","steps":["trace[1644723571] 'read index received' (duration: 22.449324ms)","trace[1644723571] 'applied index is now lower than readState.Index' (duration: 89.151017ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:16:14.132965Z","caller":"traceutil/trace.go:171","msg":"trace[2030012272] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"112.458295ms","start":"2026-07-17T18:16:14.020464Z","end":"2026-07-17T18:16:14.132922Z","steps":["trace[2030012272] 'process raft request' (duration: 112.235399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:16:14.13305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.789766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/loki-chunks-cache\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:16:14.133094Z","caller":"traceutil/trace.go:171","msg":"trace[1569603349] range","detail":"{range_begin:/registry/services/specs/monitoring/loki-chunks-cache; range_end:; response_count:0; response_revision:4833; }","duration":"111.866388ms","start":"2026-07-17T18:16:14.021216Z","end":"2026-07-17T18:16:14.133082Z","steps":["trace[1569603349] 'agreement among raft nodes before linearized reading' (duration: 111.773026ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:16:17.085163Z","caller":"traceutil/trace.go:171","msg":"trace[484468728] linearizableReadLoop","detail":"{readStateIndex:5160; appliedIndex:5159; }","duration":"106.847857ms","start":"2026-07-17T18:16:16.978285Z","end":"2026-07-17T18:16:17.085133Z","steps":["trace[484468728] 'read index received' (duration: 106.651672ms)","trace[484468728] 'applied index is now lower than readState.Index' (duration: 194.915µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:16:17.08532Z","caller":"traceutil/trace.go:171","msg":"trace[327579227] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"136.308096ms","start":"2026-07-17T18:16:16.948994Z","end":"2026-07-17T18:16:17.085302Z","steps":["trace[327579227] 'process raft request' (duration: 135.946726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:16:17.085728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.423934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-17T18:16:17.085798Z","caller":"traceutil/trace.go:171","msg":"trace[1887049848] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4973; }","duration":"107.536276ms","start":"2026-07-17T18:16:16.978248Z","end":"2026-07-17T18:16:17.085784Z","steps":["trace[1887049848] 'agreement among raft nodes before linearized reading' (duration: 107.30496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:16:19.644648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.975786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T18:16:19.644897Z","caller":"traceutil/trace.go:171","msg":"trace[1395801557] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"190.569074ms","start":"2026-07-17T18:16:19.4543Z","end":"2026-07-17T18:16:19.644869Z","steps":["trace[1395801557] 'process raft request' (duration: 44.278919ms)","trace[1395801557] 'compare' (duration: 145.861033ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:16:19.645012Z","caller":"traceutil/trace.go:171","msg":"trace[1200977071] linearizableReadLoop","detail":"{readStateIndex:5213; appliedIndex:5209; }","duration":"183.144159ms","start":"2026-07-17T18:16:19.46185Z","end":"2026-07-17T18:16:19.644994Z","steps":["trace[1200977071] 'read index received' (duration: 36.743701ms)","trace[1200977071] 'applied index is now lower than readState.Index' (duration: 146.399418ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:16:19.645149Z","caller":"traceutil/trace.go:171","msg":"trace[300585311] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"187.429888ms","start":"2026-07-17T18:16:19.457708Z","end":"2026-07-17T18:16:19.645138Z","steps":["trace[300585311] 'process raft request' (duration: 187.122669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:16:19.645352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.482478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-17T18:16:19.645397Z","caller":"traceutil/trace.go:171","msg":"trace[1726451349] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5026; }","duration":"183.538359ms","start":"2026-07-17T18:16:19.461847Z","end":"2026-07-17T18:16:19.645385Z","steps":["trace[1726451349] 'agreement among raft nodes before linearized reading' (duration: 183.404496ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:16:19.645409Z","caller":"traceutil/trace.go:171","msg":"trace[452267315] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"186.867882ms","start":"2026-07-17T18:16:19.458525Z","end":"2026-07-17T18:16:19.645393Z","steps":["trace[452267315] 'process raft request' (duration: 186.363578ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:16:19.645539Z","caller":"traceutil/trace.go:171","msg":"trace[1484264841] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"173.573193ms","start":"2026-07-17T18:16:19.471959Z","end":"2026-07-17T18:16:19.645532Z","steps":["trace[1484264841] 'process raft request' (duration: 172.963506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:16:19.645543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.171231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-17T18:16:19.645597Z","caller":"traceutil/trace.go:171","msg":"trace[762717268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5026; }","duration":"119.256273ms","start":"2026-07-17T18:16:19.526326Z","end":"2026-07-17T18:16:19.645582Z","steps":["trace[762717268] 'agreement among raft nodes before linearized reading' (duration: 119.066308ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:16:19.645004Z","caller":"traceutil/trace.go:171","msg":"trace[942625349] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"188.050424ms","start":"2026-07-17T18:16:19.456925Z","end":"2026-07-17T18:16:19.644976Z","steps":["trace[942625349] 'process raft request' (duration: 187.839848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:16:19.645893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.850686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:16:19.64597Z","caller":"traceutil/trace.go:171","msg":"trace[231684348] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:5026; }","duration":"116.956059ms","start":"2026-07-17T18:16:19.529001Z","end":"2026-07-17T18:16:19.645957Z","steps":["trace[231684348] 'agreement among raft nodes before linearized reading' (duration: 116.845576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:16:36.448735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.377673ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-17T18:16:36.650683Z","caller":"traceutil/trace.go:171","msg":"trace[1461346036] transaction","detail":"{read_only:false; response_revision:5368; number_of_response:1; }","duration":"197.583929ms","start":"2026-07-17T18:16:36.453069Z","end":"2026-07-17T18:16:36.650653Z","steps":["trace[1461346036] 'process raft request' (duration: 180.870125ms)","trace[1461346036] 'compare' (duration: 16.457697ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:17:58.236967Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2963} {"level":"info","ts":"2026-07-17T18:17:58.305527Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2963,"took":"67.396679ms","hash":2697065103,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":34426880,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-17T18:17:58.305583Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2697065103,"revision":2963,"compact-revision":-1} {"level":"info","ts":"2026-07-17T18:22:41.636987Z","caller":"traceutil/trace.go:171","msg":"trace[1657962390] transaction","detail":"{read_only:false; response_revision:8198; number_of_response:1; }","duration":"189.849286ms","start":"2026-07-17T18:22:41.447111Z","end":"2026-07-17T18:22:41.63696Z","steps":["trace[1657962390] 'process raft request' (duration: 189.747873ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:22:41.637027Z","caller":"traceutil/trace.go:171","msg":"trace[947503317] transaction","detail":"{read_only:false; response_revision:8197; number_of_response:1; }","duration":"340.143757ms","start":"2026-07-17T18:22:41.296858Z","end":"2026-07-17T18:22:41.637002Z","steps":["trace[947503317] 'process raft request' (duration: 271.363473ms)","trace[947503317] 'compare' (duration: 68.449845ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:22:41.637036Z","caller":"traceutil/trace.go:171","msg":"trace[170197630] linearizableReadLoop","detail":"{readStateIndex:8485; appliedIndex:8484; }","duration":"201.532029ms","start":"2026-07-17T18:22:41.435477Z","end":"2026-07-17T18:22:41.637009Z","steps":["trace[170197630] 'read index received' (duration: 10.682846ms)","trace[170197630] 'applied index is now lower than readState.Index' (duration: 190.843343ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:22:41.637159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:22:41.296841Z","time spent":"340.25388ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:22:41.637326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.832558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-17T18:22:41.637359Z","caller":"traceutil/trace.go:171","msg":"trace[1779951709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8198; }","duration":"201.922121ms","start":"2026-07-17T18:22:41.435427Z","end":"2026-07-17T18:22:41.637349Z","steps":["trace[1779951709] 'agreement among raft nodes before linearized reading' (duration: 201.714175ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:22:54.848106Z","caller":"traceutil/trace.go:171","msg":"trace[2143222366] transaction","detail":"{read_only:false; response_revision:8268; number_of_response:1; }","duration":"134.01338ms","start":"2026-07-17T18:22:54.714063Z","end":"2026-07-17T18:22:54.848076Z","steps":["trace[2143222366] 'process raft request' (duration: 35.186284ms)","trace[2143222366] 'compare' (duration: 98.708913ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:22:58.243523Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6119} {"level":"info","ts":"2026-07-17T18:22:58.335495Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6119,"took":"89.826177ms","hash":4114765992,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":23453696,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-17T18:22:58.335639Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4114765992,"revision":6119,"compact-revision":2963} {"level":"info","ts":"2026-07-17T18:23:21.408182Z","caller":"traceutil/trace.go:171","msg":"trace[1140533067] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"229.461729ms","start":"2026-07-17T18:23:21.178703Z","end":"2026-07-17T18:23:21.408164Z","steps":["trace[1140533067] 'process raft request' (duration: 229.234713ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:23:21.408162Z","caller":"traceutil/trace.go:171","msg":"trace[93585827] linearizableReadLoop","detail":"{readStateIndex:8765; appliedIndex:8764; }","duration":"211.957375ms","start":"2026-07-17T18:23:21.196177Z","end":"2026-07-17T18:23:21.408135Z","steps":["trace[93585827] 'read index received' (duration: 211.704598ms)","trace[93585827] 'applied index is now lower than readState.Index' (duration: 251.746µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:23:21.408374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.182191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-17T18:23:21.408453Z","caller":"traceutil/trace.go:171","msg":"trace[23959993] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:8466; }","duration":"212.273083ms","start":"2026-07-17T18:23:21.196137Z","end":"2026-07-17T18:23:21.40841Z","steps":["trace[23959993] 'agreement among raft nodes before linearized reading' (duration: 212.099158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:23:24.966339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.779109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-07-17T18:23:24.966364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.533346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-17T18:23:24.966433Z","caller":"traceutil/trace.go:171","msg":"trace[78397105] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8484; }","duration":"190.884882ms","start":"2026-07-17T18:23:24.775527Z","end":"2026-07-17T18:23:24.966412Z","steps":["trace[78397105] 'range keys from in-memory index tree' (duration: 190.556103ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:23:24.966438Z","caller":"traceutil/trace.go:171","msg":"trace[697015721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8484; }","duration":"201.642289ms","start":"2026-07-17T18:23:24.764779Z","end":"2026-07-17T18:23:24.966421Z","steps":["trace[697015721] 'range keys from in-memory index tree' (duration: 201.31395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:23:24.966512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.41301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:23:24.96657Z","caller":"traceutil/trace.go:171","msg":"trace[632214475] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:8484; }","duration":"108.515403ms","start":"2026-07-17T18:23:24.858038Z","end":"2026-07-17T18:23:24.966553Z","steps":["trace[632214475] 'count revisions from in-memory index tree' (duration: 108.324578ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:24:15.289427Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002430.wal"} {"level":"info","ts":"2026-07-17T18:24:33.851825Z","caller":"traceutil/trace.go:171","msg":"trace[861961400] transaction","detail":"{read_only:false; response_revision:9205; number_of_response:1; }","duration":"103.434752ms","start":"2026-07-17T18:24:33.748371Z","end":"2026-07-17T18:24:33.851806Z","steps":["trace[861961400] 'process raft request' (duration: 100.021468ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:25:38.136278Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"71d28e9ac1bc4e5d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-17T18:25:38.141923Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-17T18:25:38.142034Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-17T18:27:58.256161Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8293} {"level":"info","ts":"2026-07-17T18:27:58.336043Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8293,"took":"78.04806ms","hash":1015368979,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":26464256,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-17T18:27:58.336111Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1015368979,"revision":8293,"compact-revision":6119} {"level":"info","ts":"2026-07-17T18:28:31.553232Z","caller":"traceutil/trace.go:171","msg":"trace[1406441968] transaction","detail":"{read_only:false; response_revision:11155; number_of_response:1; }","duration":"126.605974ms","start":"2026-07-17T18:28:31.426595Z","end":"2026-07-17T18:28:31.553201Z","steps":["trace[1406441968] 'process raft request' (duration: 126.433519ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:28:31.574519Z","caller":"traceutil/trace.go:171","msg":"trace[624828894] transaction","detail":"{read_only:false; response_revision:11157; number_of_response:1; }","duration":"113.127121ms","start":"2026-07-17T18:28:31.461367Z","end":"2026-07-17T18:28:31.574494Z","steps":["trace[624828894] 'process raft request' (duration: 113.07467ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:28:31.574569Z","caller":"traceutil/trace.go:171","msg":"trace[1323984158] transaction","detail":"{read_only:false; response_revision:11156; number_of_response:1; }","duration":"146.706029ms","start":"2026-07-17T18:28:31.427835Z","end":"2026-07-17T18:28:31.574541Z","steps":["trace[1323984158] 'process raft request' (duration: 146.482043ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:32:58.271635Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10921} {"level":"info","ts":"2026-07-17T18:32:58.359434Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10921,"took":"85.076778ms","hash":2329076812,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":28135424,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-17T18:32:58.359522Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2329076812,"revision":10921,"compact-revision":8293} {"level":"info","ts":"2026-07-17T18:35:04.835811Z","caller":"traceutil/trace.go:171","msg":"trace[1159797257] transaction","detail":"{read_only:false; response_revision:13794; number_of_response:1; }","duration":"172.623033ms","start":"2026-07-17T18:35:04.663155Z","end":"2026-07-17T18:35:04.835778Z","steps":["trace[1159797257] 'process raft request' (duration: 172.434848ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:36:52.680062Z","caller":"traceutil/trace.go:171","msg":"trace[1710510595] transaction","detail":"{read_only:false; response_revision:15041; number_of_response:1; }","duration":"113.365321ms","start":"2026-07-17T18:36:52.56666Z","end":"2026-07-17T18:36:52.680025Z","steps":["trace[1710510595] 'process raft request' (duration: 113.163496ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:36:53.101483Z","caller":"traceutil/trace.go:171","msg":"trace[1534571138] transaction","detail":"{read_only:false; response_revision:15042; number_of_response:1; }","duration":"105.545314ms","start":"2026-07-17T18:36:52.995909Z","end":"2026-07-17T18:36:53.101454Z","steps":["trace[1534571138] 'process raft request' (duration: 104.834284ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:37:58.278851Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12983} {"level":"info","ts":"2026-07-17T18:37:58.357684Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12983,"took":"76.104588ms","hash":3088751559,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":30699520,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-17T18:37:58.357756Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3088751559,"revision":12983,"compact-revision":10921} {"level":"info","ts":"2026-07-17T18:39:44.519221Z","caller":"traceutil/trace.go:171","msg":"trace[976050386] linearizableReadLoop","detail":"{readStateIndex:16975; appliedIndex:16974; }","duration":"100.823844ms","start":"2026-07-17T18:39:44.418373Z","end":"2026-07-17T18:39:44.519197Z","steps":["trace[976050386] 'read index received' (duration: 49.330957ms)","trace[976050386] 'applied index is now lower than readState.Index' (duration: 51.491767ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:39:44.519385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.998138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T18:39:44.519426Z","caller":"traceutil/trace.go:171","msg":"trace[2137657692] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:16420; }","duration":"101.096171ms","start":"2026-07-17T18:39:44.41832Z","end":"2026-07-17T18:39:44.519416Z","steps":["trace[2137657692] 'agreement among raft nodes before linearized reading' (duration: 100.975408ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:39:44.519687Z","caller":"traceutil/trace.go:171","msg":"trace[615646911] transaction","detail":"{read_only:false; response_revision:16420; number_of_response:1; }","duration":"108.6536ms","start":"2026-07-17T18:39:44.411021Z","end":"2026-07-17T18:39:44.519674Z","steps":["trace[615646911] 'process raft request' (duration: 56.744872ms)","trace[615646911] 'compare' (duration: 51.189588ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:42:42.92171Z","caller":"traceutil/trace.go:171","msg":"trace[774956141] linearizableReadLoop","detail":"{readStateIndex:18317; appliedIndex:18316; }","duration":"106.039546ms","start":"2026-07-17T18:42:42.815646Z","end":"2026-07-17T18:42:42.921686Z","steps":["trace[774956141] 'read index received' (duration: 105.899002ms)","trace[774956141] 'applied index is now lower than readState.Index' (duration: 139.854µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:42:42.921919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.268652ms","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-07-17T18:42:42.921945Z","caller":"traceutil/trace.go:171","msg":"trace[1846545242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17721; }","duration":"106.362545ms","start":"2026-07-17T18:42:42.815572Z","end":"2026-07-17T18:42:42.921935Z","steps":["trace[1846545242] 'agreement among raft nodes before linearized reading' (duration: 106.220351ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:42:42.921953Z","caller":"traceutil/trace.go:171","msg":"trace[1947741135] transaction","detail":"{read_only:false; response_revision:17721; number_of_response:1; }","duration":"138.31036ms","start":"2026-07-17T18:42:42.783612Z","end":"2026-07-17T18:42:42.921922Z","steps":["trace[1947741135] 'process raft request' (duration: 137.95566ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:42:43.771329Z","caller":"traceutil/trace.go:171","msg":"trace[280755020] linearizableReadLoop","detail":"{readStateIndex:18322; appliedIndex:18321; }","duration":"115.59337ms","start":"2026-07-17T18:42:43.655714Z","end":"2026-07-17T18:42:43.771307Z","steps":["trace[280755020] 'read index received' (duration: 115.439306ms)","trace[280755020] 'applied index is now lower than readState.Index' (duration: 153.384µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:42:43.771438Z","caller":"traceutil/trace.go:171","msg":"trace[1098572719] transaction","detail":"{read_only:false; response_revision:17726; number_of_response:1; }","duration":"169.03332ms","start":"2026-07-17T18:42:43.602362Z","end":"2026-07-17T18:42:43.771395Z","steps":["trace[1098572719] 'process raft request' (duration: 168.835775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:42:43.771475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.741185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T18:42:43.771615Z","caller":"traceutil/trace.go:171","msg":"trace[1402228030] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:17726; }","duration":"115.884539ms","start":"2026-07-17T18:42:43.655699Z","end":"2026-07-17T18:42:43.771583Z","steps":["trace[1402228030] 'agreement among raft nodes before linearized reading' (duration: 115.695643ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:42:43.961235Z","caller":"traceutil/trace.go:171","msg":"trace[512641589] transaction","detail":"{read_only:false; response_revision:17727; number_of_response:1; }","duration":"185.873976ms","start":"2026-07-17T18:42:43.775326Z","end":"2026-07-17T18:42:43.9612Z","steps":["trace[512641589] 'process raft request' (duration: 137.063405ms)","trace[512641589] 'compare' (duration: 47.548506ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:42:48.998064Z","caller":"traceutil/trace.go:171","msg":"trace[1352298482] transaction","detail":"{read_only:false; response_revision:17847; number_of_response:1; }","duration":"132.564931ms","start":"2026-07-17T18:42:48.865427Z","end":"2026-07-17T18:42:48.997992Z","steps":["trace[1352298482] 'process raft request' (duration: 132.381236ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:42:48.998388Z","caller":"traceutil/trace.go:171","msg":"trace[981162331] transaction","detail":"{read_only:false; response_revision:17848; number_of_response:1; }","duration":"131.507981ms","start":"2026-07-17T18:42:48.86687Z","end":"2026-07-17T18:42:48.998378Z","steps":["trace[981162331] 'process raft request' (duration: 131.063449ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:42:48.998578Z","caller":"traceutil/trace.go:171","msg":"trace[3427133] transaction","detail":"{read_only:false; response_revision:17849; number_of_response:1; }","duration":"130.051011ms","start":"2026-07-17T18:42:48.868498Z","end":"2026-07-17T18:42:48.998549Z","steps":["trace[3427133] 'process raft request' (duration: 129.718132ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:42:48.998819Z","caller":"traceutil/trace.go:171","msg":"trace[928354733] transaction","detail":"{read_only:false; response_revision:17851; number_of_response:1; }","duration":"128.003043ms","start":"2026-07-17T18:42:48.870807Z","end":"2026-07-17T18:42:48.99881Z","steps":["trace[928354733] 'process raft request' (duration: 127.670344ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:42:48.998867Z","caller":"traceutil/trace.go:171","msg":"trace[1120891812] transaction","detail":"{read_only:false; response_revision:17852; number_of_response:1; }","duration":"128.015414ms","start":"2026-07-17T18:42:48.870821Z","end":"2026-07-17T18:42:48.998837Z","steps":["trace[1120891812] 'process raft request' (duration: 127.824439ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:42:48.998949Z","caller":"traceutil/trace.go:171","msg":"trace[810456073] transaction","detail":"{read_only:false; response_revision:17850; number_of_response:1; }","duration":"130.197025ms","start":"2026-07-17T18:42:48.868705Z","end":"2026-07-17T18:42:48.998902Z","steps":["trace[810456073] 'process raft request' (duration: 129.688171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:42:49.002215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.351311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:42:49.002286Z","caller":"traceutil/trace.go:171","msg":"trace[284128023] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-init; range_end:; response_count:0; response_revision:17853; }","duration":"129.448784ms","start":"2026-07-17T18:42:48.872822Z","end":"2026-07-17T18:42:49.002271Z","steps":["trace[284128023] 'agreement among raft nodes before linearized reading' (duration: 129.342391ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:42:58.285643Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15472} {"level":"info","ts":"2026-07-17T18:42:58.384845Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15472,"took":"95.525785ms","hash":1655875288,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":33361920,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-17T18:42:58.384939Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1655875288,"revision":15472,"compact-revision":12983} {"level":"info","ts":"2026-07-17T18:46:03.758751Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"71d28e9ac1bc4e5d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-17T18:46:03.76409Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-17T18:46:03.764213Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-17T18:47:50.626281Z","caller":"traceutil/trace.go:171","msg":"trace[2026619720] transaction","detail":"{read_only:false; response_revision:20096; number_of_response:1; }","duration":"123.404758ms","start":"2026-07-17T18:47:50.502845Z","end":"2026-07-17T18:47:50.62625Z","steps":["trace[2026619720] 'process raft request' (duration: 123.028008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:47:50.80349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.10938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-17T18:47:50.803725Z","caller":"traceutil/trace.go:171","msg":"trace[1604903449] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:20096; }","duration":"131.319486ms","start":"2026-07-17T18:47:50.67231Z","end":"2026-07-17T18:47:50.80363Z","steps":["trace[1604903449] 'count revisions from in-memory index tree' (duration: 131.028388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:47:56.453998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.579147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T18:47:56.45412Z","caller":"traceutil/trace.go:171","msg":"trace[1026856653] transaction","detail":"{read_only:false; response_revision:20124; number_of_response:1; }","duration":"287.343418ms","start":"2026-07-17T18:47:56.166755Z","end":"2026-07-17T18:47:56.454098Z","steps":["trace[1026856653] 'process raft request' (duration: 113.557475ms)","trace[1026856653] 'compare' (duration: 173.457334ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:47:56.459588Z","caller":"traceutil/trace.go:171","msg":"trace[1082948886] transaction","detail":"{read_only:false; response_revision:20125; number_of_response:1; }","duration":"141.021396ms","start":"2026-07-17T18:47:56.318551Z","end":"2026-07-17T18:47:56.459572Z","steps":["trace[1082948886] 'process raft request' (duration: 140.929953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:47:56.773581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.810471ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-17T18:47:56.773741Z","caller":"traceutil/trace.go:171","msg":"trace[1228886479] linearizableReadLoop","detail":"{readStateIndex:20802; appliedIndex:20801; }","duration":"126.447122ms","start":"2026-07-17T18:47:56.647229Z","end":"2026-07-17T18:47:56.773676Z","steps":["trace[1228886479] 'read index received' (duration: 68.332µs)","trace[1228886479] 'applied index is now lower than readState.Index' (duration: 126.3773ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:47:56.774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.75018ms","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-07-17T18:47:56.774091Z","caller":"traceutil/trace.go:171","msg":"trace[1597377156] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20125; }","duration":"126.899084ms","start":"2026-07-17T18:47:56.647172Z","end":"2026-07-17T18:47:56.774071Z","steps":["trace[1597377156] 'agreement among raft nodes before linearized reading' (duration: 126.595166ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:47:56.909317Z","caller":"traceutil/trace.go:171","msg":"trace[575018141] transaction","detail":"{read_only:false; response_revision:20126; number_of_response:1; }","duration":"131.330407ms","start":"2026-07-17T18:47:56.777961Z","end":"2026-07-17T18:47:56.909292Z","steps":["trace[575018141] 'process raft request' (duration: 131.173303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:47:57.163562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.654572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:47:57.16365Z","caller":"traceutil/trace.go:171","msg":"trace[1562507323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20126; }","duration":"150.796026ms","start":"2026-07-17T18:47:57.012832Z","end":"2026-07-17T18:47:57.163628Z","steps":["trace[1562507323] 'range keys from in-memory index tree' (duration: 150.521929ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:47:57.406459Z","caller":"traceutil/trace.go:171","msg":"trace[1026528903] linearizableReadLoop","detail":"{readStateIndex:20804; appliedIndex:20803; }","duration":"241.947231ms","start":"2026-07-17T18:47:57.164494Z","end":"2026-07-17T18:47:57.406441Z","steps":["trace[1026528903] 'read index received' (duration: 241.801517ms)","trace[1026528903] 'applied index is now lower than readState.Index' (duration: 144.854µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:47:57.406507Z","caller":"traceutil/trace.go:171","msg":"trace[218824853] transaction","detail":"{read_only:false; response_revision:20127; number_of_response:1; }","duration":"310.107588ms","start":"2026-07-17T18:47:57.096368Z","end":"2026-07-17T18:47:57.406476Z","steps":["trace[218824853] 'process raft request' (duration: 309.940263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:47:57.406643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:47:57.09634Z","time spent":"310.231031ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:47:57.406687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.681148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-17T18:47:57.406785Z","caller":"traceutil/trace.go:171","msg":"trace[1663464843] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20127; }","duration":"187.750829ms","start":"2026-07-17T18:47:57.21896Z","end":"2026-07-17T18:47:57.406711Z","steps":["trace[1663464843] 'agreement among raft nodes before linearized reading' (duration: 187.638066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:47:57.406775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.641306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1987"} {"level":"info","ts":"2026-07-17T18:47:57.406832Z","caller":"traceutil/trace.go:171","msg":"trace[845450214] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:20127; }","duration":"209.735588ms","start":"2026-07-17T18:47:57.197079Z","end":"2026-07-17T18:47:57.406815Z","steps":["trace[845450214] 'agreement among raft nodes before linearized reading' (duration: 209.517292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:47:57.407006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.813273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T18:47:57.407037Z","caller":"traceutil/trace.go:171","msg":"trace[627845453] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:20127; }","duration":"143.954427ms","start":"2026-07-17T18:47:57.263073Z","end":"2026-07-17T18:47:57.407028Z","steps":["trace[627845453] 'agreement among raft nodes before linearized reading' (duration: 143.807403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:47:57.407136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.066604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:47:57.407163Z","caller":"traceutil/trace.go:171","msg":"trace[543694050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20127; }","duration":"242.696701ms","start":"2026-07-17T18:47:57.164454Z","end":"2026-07-17T18:47:57.407151Z","steps":["trace[543694050] 'agreement among raft nodes before linearized reading' (duration: 242.080894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:47:57.644761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.892227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-17T18:47:57.644883Z","caller":"traceutil/trace.go:171","msg":"trace[956392129] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20128; }","duration":"121.036141ms","start":"2026-07-17T18:47:57.523809Z","end":"2026-07-17T18:47:57.644845Z","steps":["trace[956392129] 'range keys from in-memory index tree' (duration: 120.714112ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:47:58.295004Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18034} {"level":"info","ts":"2026-07-17T18:47:58.412305Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18034,"took":"113.414691ms","hash":1472778367,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":30212096,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-17T18:47:58.412379Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1472778367,"revision":18034,"compact-revision":15472} {"level":"info","ts":"2026-07-17T18:51:40.745424Z","caller":"traceutil/trace.go:171","msg":"trace[97987922] transaction","detail":"{read_only:false; response_revision:22514; number_of_response:1; }","duration":"142.472616ms","start":"2026-07-17T18:51:40.60293Z","end":"2026-07-17T18:51:40.745403Z","steps":["trace[97987922] 'process raft request' (duration: 142.367004ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:51:40.767154Z","caller":"traceutil/trace.go:171","msg":"trace[1989339333] transaction","detail":"{read_only:false; response_revision:22515; number_of_response:1; }","duration":"163.940491ms","start":"2026-07-17T18:51:40.603192Z","end":"2026-07-17T18:51:40.767132Z","steps":["trace[1989339333] 'process raft request' (duration: 163.720045ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:51:40.767554Z","caller":"traceutil/trace.go:171","msg":"trace[268397766] transaction","detail":"{read_only:false; response_revision:22516; number_of_response:1; }","duration":"164.23959ms","start":"2026-07-17T18:51:40.603305Z","end":"2026-07-17T18:51:40.767545Z","steps":["trace[268397766] 'process raft request' (duration: 163.740466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:51:41.04064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.310674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T18:51:41.040797Z","caller":"traceutil/trace.go:171","msg":"trace[205267336] transaction","detail":"{read_only:false; response_revision:22517; number_of_response:1; }","duration":"268.431967ms","start":"2026-07-17T18:51:40.77233Z","end":"2026-07-17T18:51:41.040762Z","steps":["trace[205267336] 'process raft request' (duration: 165.860535ms)","trace[205267336] 'compare' (duration: 102.122779ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:51:41.045763Z","caller":"traceutil/trace.go:171","msg":"trace[2136463531] transaction","detail":"{read_only:false; response_revision:22518; number_of_response:1; }","duration":"271.998576ms","start":"2026-07-17T18:51:40.773736Z","end":"2026-07-17T18:51:41.045734Z","steps":["trace[2136463531] 'process raft request' (duration: 271.820881ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:51:41.046067Z","caller":"traceutil/trace.go:171","msg":"trace[2044121729] transaction","detail":"{read_only:false; response_revision:22519; number_of_response:1; }","duration":"272.17298ms","start":"2026-07-17T18:51:40.773827Z","end":"2026-07-17T18:51:41.046Z","steps":["trace[2044121729] 'process raft request' (duration: 271.862791ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:51:41.046133Z","caller":"traceutil/trace.go:171","msg":"trace[166953270] linearizableReadLoop","detail":"{readStateIndex:23273; appliedIndex:23267; }","duration":"161.463213ms","start":"2026-07-17T18:51:40.884653Z","end":"2026-07-17T18:51:41.046116Z","steps":["trace[166953270] 'read index received' (duration: 53.585395ms)","trace[166953270] 'applied index is now lower than readState.Index' (duration: 107.876788ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:51:41.046078Z","caller":"traceutil/trace.go:171","msg":"trace[1603472346] transaction","detail":"{read_only:false; response_revision:22520; number_of_response:1; }","duration":"270.195286ms","start":"2026-07-17T18:51:40.77587Z","end":"2026-07-17T18:51:41.046065Z","steps":["trace[1603472346] 'process raft request' (duration: 270.018501ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:51:41.046354Z","caller":"traceutil/trace.go:171","msg":"trace[982565659] transaction","detail":"{read_only:false; response_revision:22521; number_of_response:1; }","duration":"268.051626ms","start":"2026-07-17T18:51:40.778283Z","end":"2026-07-17T18:51:41.046335Z","steps":["trace[982565659] 'process raft request' (duration: 267.703167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:51:41.046673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.001208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-17T18:51:41.046818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.57636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T18:51:41.046875Z","caller":"traceutil/trace.go:171","msg":"trace[805837213] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:22523; }","duration":"107.665153ms","start":"2026-07-17T18:51:40.939195Z","end":"2026-07-17T18:51:41.04686Z","steps":["trace[805837213] 'agreement among raft nodes before linearized reading' (duration: 107.509808ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:51:41.046915Z","caller":"traceutil/trace.go:171","msg":"trace[439618850] transaction","detail":"{read_only:false; response_revision:22522; number_of_response:1; }","duration":"257.643218ms","start":"2026-07-17T18:51:40.789229Z","end":"2026-07-17T18:51:41.046873Z","steps":["trace[439618850] 'process raft request' (duration: 256.822755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:51:41.046839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.409911ms","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-07-17T18:51:41.047094Z","caller":"traceutil/trace.go:171","msg":"trace[1026611858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22523; }","duration":"161.778062ms","start":"2026-07-17T18:51:40.885295Z","end":"2026-07-17T18:51:41.047073Z","steps":["trace[1026611858] 'agreement among raft nodes before linearized reading' (duration: 161.320659ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:51:41.046792Z","caller":"traceutil/trace.go:171","msg":"trace[1130907158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22522; }","duration":"162.169832ms","start":"2026-07-17T18:51:40.884603Z","end":"2026-07-17T18:51:41.046772Z","steps":["trace[1130907158] 'agreement among raft nodes before linearized reading' (duration: 161.913895ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:52:58.30087Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20134} {"level":"info","ts":"2026-07-17T18:52:58.40465Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20134,"took":"98.889419ms","hash":3139168976,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":43339776,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-07-17T18:52:58.404705Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3139168976,"revision":20134,"compact-revision":18034} {"level":"warn","ts":"2026-07-17T18:55:08.379848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.332776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T18:55:08.381199Z","caller":"traceutil/trace.go:171","msg":"trace[1655662317] linearizableReadLoop","detail":"{readStateIndex:25430; appliedIndex:25427; }","duration":"247.42971ms","start":"2026-07-17T18:55:08.133743Z","end":"2026-07-17T18:55:08.381173Z","steps":["trace[1655662317] 'read index received' (duration: 84.651444ms)","trace[1655662317] 'applied index is now lower than readState.Index' (duration: 162.776746ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:55:08.381338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.580084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:08.381387Z","caller":"traceutil/trace.go:171","msg":"trace[1484513314] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:24625; }","duration":"247.659336ms","start":"2026-07-17T18:55:08.133714Z","end":"2026-07-17T18:55:08.381373Z","steps":["trace[1484513314] 'agreement among raft nodes before linearized reading' (duration: 247.531313ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:08.38167Z","caller":"traceutil/trace.go:171","msg":"trace[1452641581] transaction","detail":"{read_only:false; response_revision:24623; number_of_response:1; }","duration":"279.772335ms","start":"2026-07-17T18:55:08.101881Z","end":"2026-07-17T18:55:08.381653Z","steps":["trace[1452641581] 'process raft request' (duration: 116.504375ms)","trace[1452641581] 'compare' (duration: 161.127181ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:08.381934Z","caller":"traceutil/trace.go:171","msg":"trace[1196054851] transaction","detail":"{read_only:false; response_revision:24624; number_of_response:1; }","duration":"280.142344ms","start":"2026-07-17T18:55:08.101778Z","end":"2026-07-17T18:55:08.38192Z","steps":["trace[1196054851] 'process raft request' (duration: 279.25739ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:08.382065Z","caller":"traceutil/trace.go:171","msg":"trace[280543561] transaction","detail":"{read_only:false; response_revision:24625; number_of_response:1; }","duration":"279.681872ms","start":"2026-07-17T18:55:08.102373Z","end":"2026-07-17T18:55:08.382055Z","steps":["trace[280543561] 'process raft request' (duration: 278.748336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:08.779143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.678479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-7dcb7c79b6-8w5mr\" ","response":"range_response_count:1 size:8652"} {"level":"info","ts":"2026-07-17T18:55:08.77921Z","caller":"traceutil/trace.go:171","msg":"trace[457433947] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-7dcb7c79b6-8w5mr; range_end:; response_count:1; response_revision:24625; }","duration":"181.797073ms","start":"2026-07-17T18:55:08.597397Z","end":"2026-07-17T18:55:08.779194Z","steps":["trace[457433947] 'range keys from in-memory index tree' (duration: 181.501055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:08.779253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.840198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T18:55:08.779352Z","caller":"traceutil/trace.go:171","msg":"trace[500271193] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:24625; }","duration":"127.985402ms","start":"2026-07-17T18:55:08.651339Z","end":"2026-07-17T18:55:08.779324Z","steps":["trace[500271193] 'count revisions from in-memory index tree' (duration: 127.726496ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:08.939318Z","caller":"traceutil/trace.go:171","msg":"trace[171096453] linearizableReadLoop","detail":"{readStateIndex:25431; appliedIndex:25430; }","duration":"106.278702ms","start":"2026-07-17T18:55:08.833011Z","end":"2026-07-17T18:55:08.93929Z","steps":["trace[171096453] 'read index received' (duration: 106.150658ms)","trace[171096453] 'applied index is now lower than readState.Index' (duration: 126.894µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:55:08.939467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.432817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T18:55:08.939513Z","caller":"traceutil/trace.go:171","msg":"trace[2116630039] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:24626; }","duration":"106.503688ms","start":"2026-07-17T18:55:08.832994Z","end":"2026-07-17T18:55:08.939498Z","steps":["trace[2116630039] 'agreement among raft nodes before linearized reading' (duration: 106.400526ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:08.939706Z","caller":"traceutil/trace.go:171","msg":"trace[754855875] transaction","detail":"{read_only:false; response_revision:24626; number_of_response:1; }","duration":"150.342092ms","start":"2026-07-17T18:55:08.789324Z","end":"2026-07-17T18:55:08.939666Z","steps":["trace[754855875] 'process raft request' (duration: 149.788747ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:09.201746Z","caller":"traceutil/trace.go:171","msg":"trace[1556260413] transaction","detail":"{read_only:false; response_revision:24628; number_of_response:1; }","duration":"325.707707ms","start":"2026-07-17T18:55:08.876002Z","end":"2026-07-17T18:55:09.201709Z","steps":["trace[1556260413] 'process raft request' (duration: 325.640135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:09.201964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:08.875977Z","time spent":"325.85795ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:09.202109Z","caller":"traceutil/trace.go:171","msg":"trace[286648478] transaction","detail":"{read_only:false; response_revision:24627; number_of_response:1; }","duration":"359.113811ms","start":"2026-07-17T18:55:08.842984Z","end":"2026-07-17T18:55:09.202097Z","steps":["trace[286648478] 'process raft request' (duration: 358.468234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:09.202189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:08.842966Z","time spent":"359.189233ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:09.253406Z","caller":"traceutil/trace.go:171","msg":"trace[285806074] linearizableReadLoop","detail":"{readStateIndex:25437; appliedIndex:25431; }","duration":"288.899477ms","start":"2026-07-17T18:55:08.964472Z","end":"2026-07-17T18:55:09.253371Z","steps":["trace[285806074] 'read index received' (duration: 236.916328ms)","trace[285806074] 'applied index is now lower than readState.Index' (duration: 51.982239ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:09.253775Z","caller":"traceutil/trace.go:171","msg":"trace[1775616673] transaction","detail":"{read_only:false; response_revision:24629; number_of_response:1; }","duration":"307.437341ms","start":"2026-07-17T18:55:08.946323Z","end":"2026-07-17T18:55:09.253761Z","steps":["trace[1775616673] 'process raft request' (duration: 306.669509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:09.253888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:08.946305Z","time spent":"307.499562ms","remote":"127.0.0.1:46492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:09.25417Z","caller":"traceutil/trace.go:171","msg":"trace[1960233908] transaction","detail":"{read_only:false; response_revision:24630; number_of_response:1; }","duration":"307.76665ms","start":"2026-07-17T18:55:08.94639Z","end":"2026-07-17T18:55:09.254157Z","steps":["trace[1960233908] 'process raft request' (duration: 306.766292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:09.254229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:08.946364Z","time spent":"307.831802ms","remote":"127.0.0.1:46450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1168,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:09.254446Z","caller":"traceutil/trace.go:171","msg":"trace[1065491507] transaction","detail":"{read_only:false; response_revision:24631; number_of_response:1; }","duration":"307.676607ms","start":"2026-07-17T18:55:08.946759Z","end":"2026-07-17T18:55:09.254435Z","steps":["trace[1065491507] 'process raft request' (duration: 306.456284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:09.254503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:08.946707Z","time spent":"307.762009ms","remote":"127.0.0.1:46324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:09.254946Z","caller":"traceutil/trace.go:171","msg":"trace[570584481] transaction","detail":"{read_only:false; response_revision:24632; number_of_response:1; }","duration":"305.690972ms","start":"2026-07-17T18:55:08.94924Z","end":"2026-07-17T18:55:09.254931Z","steps":["trace[570584481] 'process raft request' (duration: 304.024946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:09.255016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:08.94923Z","time spent":"305.748183ms","remote":"127.0.0.1:46654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7536,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:09.255259Z","caller":"traceutil/trace.go:171","msg":"trace[1230549575] transaction","detail":"{read_only:false; response_revision:24633; number_of_response:1; }","duration":"239.644304ms","start":"2026-07-17T18:55:09.015603Z","end":"2026-07-17T18:55:09.255247Z","steps":["trace[1230549575] 'process raft request' (duration: 237.716271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:09.255471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.002346ms","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-07-17T18:55:09.255503Z","caller":"traceutil/trace.go:171","msg":"trace[27682200] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24633; }","duration":"291.057177ms","start":"2026-07-17T18:55:08.964436Z","end":"2026-07-17T18:55:09.255494Z","steps":["trace[27682200] 'agreement among raft nodes before linearized reading' (duration: 290.943143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:09.255637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.360928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:09.255663Z","caller":"traceutil/trace.go:171","msg":"trace[32431094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24633; }","duration":"242.430891ms","start":"2026-07-17T18:55:09.013224Z","end":"2026-07-17T18:55:09.255655Z","steps":["trace[32431094] 'agreement among raft nodes before linearized reading' (duration: 242.38787ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:09.554956Z","caller":"traceutil/trace.go:171","msg":"trace[1093413387] transaction","detail":"{read_only:false; response_revision:24635; number_of_response:1; }","duration":"284.150676ms","start":"2026-07-17T18:55:09.270774Z","end":"2026-07-17T18:55:09.554925Z","steps":["trace[1093413387] 'process raft request' (duration: 283.885368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:09.963422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.85333ms","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-07-17T18:55:09.963498Z","caller":"traceutil/trace.go:171","msg":"trace[83477442] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24636; }","duration":"239.971933ms","start":"2026-07-17T18:55:09.72351Z","end":"2026-07-17T18:55:09.963481Z","steps":["trace[83477442] 'range keys from in-memory index tree' (duration: 239.684575ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:10.211493Z","caller":"traceutil/trace.go:171","msg":"trace[1652746447] transaction","detail":"{read_only:false; response_revision:24637; number_of_response:1; }","duration":"408.93259ms","start":"2026-07-17T18:55:09.802525Z","end":"2026-07-17T18:55:10.211458Z","steps":["trace[1652746447] 'process raft request' (duration: 407.896701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:10.211789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:09.802498Z","time spent":"409.196077ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:10.241435Z","caller":"traceutil/trace.go:171","msg":"trace[432744242] transaction","detail":"{read_only:false; response_revision:24638; number_of_response:1; }","duration":"432.420811ms","start":"2026-07-17T18:55:09.808972Z","end":"2026-07-17T18:55:10.241393Z","steps":["trace[432744242] 'process raft request' (duration: 432.132683ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:10.241457Z","caller":"traceutil/trace.go:171","msg":"trace[197846275] transaction","detail":"{read_only:false; response_revision:24641; number_of_response:1; }","duration":"138.921416ms","start":"2026-07-17T18:55:10.102508Z","end":"2026-07-17T18:55:10.241429Z","steps":["trace[197846275] 'process raft request' (duration: 138.832103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:10.241616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:09.808944Z","time spent":"432.591584ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:10.24146Z","caller":"traceutil/trace.go:171","msg":"trace[1834773757] transaction","detail":"{read_only:false; response_revision:24640; number_of_response:1; }","duration":"142.773232ms","start":"2026-07-17T18:55:10.098662Z","end":"2026-07-17T18:55:10.241435Z","steps":["trace[1834773757] 'process raft request' (duration: 142.639468ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:10.241686Z","caller":"traceutil/trace.go:171","msg":"trace[997812775] transaction","detail":"{read_only:false; response_revision:24639; number_of_response:1; }","duration":"238.592985ms","start":"2026-07-17T18:55:10.002982Z","end":"2026-07-17T18:55:10.241575Z","steps":["trace[997812775] 'process raft request' (duration: 238.276986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:12.260074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.972372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:12.260139Z","caller":"traceutil/trace.go:171","msg":"trace[1559237481] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:24652; }","duration":"314.087755ms","start":"2026-07-17T18:55:11.946036Z","end":"2026-07-17T18:55:12.260124Z","steps":["trace[1559237481] 'count revisions from in-memory index tree' (duration: 313.90168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:12.260168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:11.946011Z","time spent":"314.150226ms","remote":"127.0.0.1:46248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-07-17T18:55:12.260333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.074297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:12.260455Z","caller":"traceutil/trace.go:171","msg":"trace[492506105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24652; }","duration":"248.206511ms","start":"2026-07-17T18:55:12.012194Z","end":"2026-07-17T18:55:12.260401Z","steps":["trace[492506105] 'range keys from in-memory index tree' (duration: 247.976285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:12.260506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.8671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-17T18:55:12.260608Z","caller":"traceutil/trace.go:171","msg":"trace[162100510] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24652; }","duration":"650.014714ms","start":"2026-07-17T18:55:11.610569Z","end":"2026-07-17T18:55:12.260584Z","steps":["trace[162100510] 'range keys from in-memory index tree' (duration: 649.645014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:12.260651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:11.61055Z","time spent":"650.092046ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T18:55:12.26089Z","caller":"traceutil/trace.go:171","msg":"trace[1940997635] linearizableReadLoop","detail":"{readStateIndex:25459; appliedIndex:25458; }","duration":"113.076811ms","start":"2026-07-17T18:55:12.147788Z","end":"2026-07-17T18:55:12.260865Z","steps":["trace[1940997635] 'read index received' (duration: 51.033883ms)","trace[1940997635] 'applied index is now lower than readState.Index' (duration: 62.041878ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:55:12.261074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.279856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T18:55:12.261086Z","caller":"traceutil/trace.go:171","msg":"trace[1086009299] transaction","detail":"{read_only:false; response_revision:24653; number_of_response:1; }","duration":"179.287753ms","start":"2026-07-17T18:55:12.081783Z","end":"2026-07-17T18:55:12.26107Z","steps":["trace[1086009299] 'process raft request' (duration: 117.067791ms)","trace[1086009299] 'compare' (duration: 61.136432ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:12.261115Z","caller":"traceutil/trace.go:171","msg":"trace[739437406] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:24653; }","duration":"113.327027ms","start":"2026-07-17T18:55:12.147778Z","end":"2026-07-17T18:55:12.261105Z","steps":["trace[739437406] 'agreement among raft nodes before linearized reading' (duration: 113.154903ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:12.549744Z","caller":"traceutil/trace.go:171","msg":"trace[1891823829] transaction","detail":"{read_only:false; response_revision:24655; number_of_response:1; }","duration":"300.921451ms","start":"2026-07-17T18:55:12.248798Z","end":"2026-07-17T18:55:12.549719Z","steps":["trace[1891823829] 'process raft request' (duration: 300.652363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:12.54989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:12.248771Z","time spent":"301.042203ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:12.550022Z","caller":"traceutil/trace.go:171","msg":"trace[1278908627] transaction","detail":"{read_only:false; response_revision:24656; number_of_response:1; }","duration":"301.093615ms","start":"2026-07-17T18:55:12.248898Z","end":"2026-07-17T18:55:12.549992Z","steps":["trace[1278908627] 'process raft request' (duration: 300.712295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:12.550183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:12.248891Z","time spent":"301.203308ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:12.550295Z","caller":"traceutil/trace.go:171","msg":"trace[1925686357] linearizableReadLoop","detail":"{readStateIndex:25464; appliedIndex:25460; }","duration":"288.026543ms","start":"2026-07-17T18:55:12.262255Z","end":"2026-07-17T18:55:12.550281Z","steps":["trace[1925686357] 'read index received' (duration: 287.279193ms)","trace[1925686357] 'applied index is now lower than readState.Index' (duration: 746.29µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:12.550318Z","caller":"traceutil/trace.go:171","msg":"trace[797098566] transaction","detail":"{read_only:false; response_revision:24657; number_of_response:1; }","duration":"301.27233ms","start":"2026-07-17T18:55:12.249034Z","end":"2026-07-17T18:55:12.550307Z","steps":["trace[797098566] 'process raft request' (duration: 301.146987ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:12.550372Z","caller":"traceutil/trace.go:171","msg":"trace[1506646708] transaction","detail":"{read_only:false; response_revision:24658; number_of_response:1; }","duration":"300.116108ms","start":"2026-07-17T18:55:12.250247Z","end":"2026-07-17T18:55:12.550363Z","steps":["trace[1506646708] 'process raft request' (duration: 299.998894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:12.550444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:12.250238Z","time spent":"300.16309ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:55:12.550372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:12.249023Z","time spent":"301.318701ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:55:12.55051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.247389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:12.550554Z","caller":"traceutil/trace.go:171","msg":"trace[98904818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24658; }","duration":"288.314902ms","start":"2026-07-17T18:55:12.262226Z","end":"2026-07-17T18:55:12.550541Z","steps":["trace[98904818] 'agreement among raft nodes before linearized reading' (duration: 288.24325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:12.553303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.641387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:12.553368Z","caller":"traceutil/trace.go:171","msg":"trace[1209582196] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:24659; }","duration":"147.713519ms","start":"2026-07-17T18:55:12.405639Z","end":"2026-07-17T18:55:12.553352Z","steps":["trace[1209582196] 'agreement among raft nodes before linearized reading' (duration: 147.585916ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:12.553597Z","caller":"traceutil/trace.go:171","msg":"trace[1825988990] transaction","detail":"{read_only:false; response_revision:24659; number_of_response:1; }","duration":"281.278177ms","start":"2026-07-17T18:55:12.272277Z","end":"2026-07-17T18:55:12.553555Z","steps":["trace[1825988990] 'process raft request' (duration: 280.756522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:12.553898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.406715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-17T18:55:12.55394Z","caller":"traceutil/trace.go:171","msg":"trace[616622330] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24659; }","duration":"158.464777ms","start":"2026-07-17T18:55:12.395463Z","end":"2026-07-17T18:55:12.553928Z","steps":["trace[616622330] 'agreement among raft nodes before linearized reading' (duration: 158.368735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:12.554029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.530041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-17T18:55:12.554062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.914251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-17T18:55:12.5541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.397077ms","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-07-17T18:55:12.554196Z","caller":"traceutil/trace.go:171","msg":"trace[1063784975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24659; }","duration":"157.535041ms","start":"2026-07-17T18:55:12.396642Z","end":"2026-07-17T18:55:12.554178Z","steps":["trace[1063784975] 'agreement among raft nodes before linearized reading' (duration: 157.240343ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:12.554118Z","caller":"traceutil/trace.go:171","msg":"trace[197990514] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:24659; }","duration":"188.012894ms","start":"2026-07-17T18:55:12.366094Z","end":"2026-07-17T18:55:12.554107Z","steps":["trace[197990514] 'agreement among raft nodes before linearized reading' (duration: 187.065158ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:12.554122Z","caller":"traceutil/trace.go:171","msg":"trace[94891065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24659; }","duration":"157.646844ms","start":"2026-07-17T18:55:12.396455Z","end":"2026-07-17T18:55:12.554101Z","steps":["trace[94891065] 'agreement among raft nodes before linearized reading' (duration: 157.368566ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:22.728083Z","caller":"traceutil/trace.go:171","msg":"trace[1996619708] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"140.797947ms","start":"2026-07-17T18:55:22.587258Z","end":"2026-07-17T18:55:22.728055Z","steps":["trace[1996619708] 'process raft request' (duration: 140.601842ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:22.91397Z","caller":"traceutil/trace.go:171","msg":"trace[1892691165] transaction","detail":"{read_only:false; response_revision:24741; number_of_response:1; }","duration":"325.271584ms","start":"2026-07-17T18:55:22.588653Z","end":"2026-07-17T18:55:22.913925Z","steps":["trace[1892691165] 'process raft request' (duration: 325.176532ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:22.913993Z","caller":"traceutil/trace.go:171","msg":"trace[1714834851] transaction","detail":"{read_only:false; response_revision:24740; number_of_response:1; }","duration":"326.133928ms","start":"2026-07-17T18:55:22.587827Z","end":"2026-07-17T18:55:22.913961Z","steps":["trace[1714834851] 'process raft request' (duration: 325.83766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:22.914125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:22.587807Z","time spent":"326.242461ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:55:22.914137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:22.588649Z","time spent":"325.380687ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:22.914258Z","caller":"traceutil/trace.go:171","msg":"trace[1899523803] transaction","detail":"{read_only:false; response_revision:24742; number_of_response:1; }","duration":"321.641604ms","start":"2026-07-17T18:55:22.592583Z","end":"2026-07-17T18:55:22.914225Z","steps":["trace[1899523803] 'process raft request' (duration: 321.309785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:22.91446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:22.592569Z","time spent":"321.805178ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:22.914522Z","caller":"traceutil/trace.go:171","msg":"trace[64117883] linearizableReadLoop","detail":"{readStateIndex:25551; appliedIndex:25547; }","duration":"319.016451ms","start":"2026-07-17T18:55:22.595481Z","end":"2026-07-17T18:55:22.914497Z","steps":["trace[64117883] 'read index received' (duration: 132.442966ms)","trace[64117883] 'applied index is now lower than readState.Index' (duration: 186.572565ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:22.914638Z","caller":"traceutil/trace.go:171","msg":"trace[1486061172] transaction","detail":"{read_only:false; response_revision:24743; number_of_response:1; }","duration":"258.947808ms","start":"2026-07-17T18:55:22.655678Z","end":"2026-07-17T18:55:22.914626Z","steps":["trace[1486061172] 'process raft request' (duration: 258.522736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:22.914909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.304493ms","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-07-17T18:55:22.914932Z","caller":"traceutil/trace.go:171","msg":"trace[1084036724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24743; }","duration":"313.358214ms","start":"2026-07-17T18:55:22.601568Z","end":"2026-07-17T18:55:22.914926Z","steps":["trace[1084036724] 'agreement among raft nodes before linearized reading' (duration: 313.274572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:22.914948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:22.601551Z","time spent":"313.393466ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T18:55:22.914943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.53383ms","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-07-17T18:55:22.915009Z","caller":"traceutil/trace.go:171","msg":"trace[662766323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24743; }","duration":"317.639493ms","start":"2026-07-17T18:55:22.597352Z","end":"2026-07-17T18:55:22.914992Z","steps":["trace[662766323] 'agreement among raft nodes before linearized reading' (duration: 317.434537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:22.915048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.561226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-17T18:55:22.915056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:22.597326Z","time spent":"317.718275ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T18:55:22.91509Z","caller":"traceutil/trace.go:171","msg":"trace[1991258267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24743; }","duration":"319.638808ms","start":"2026-07-17T18:55:22.595439Z","end":"2026-07-17T18:55:22.915078Z","steps":["trace[1991258267] 'agreement among raft nodes before linearized reading' (duration: 319.519375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:22.915114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.807917ms","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-07-17T18:55:22.915123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:22.595432Z","time spent":"319.682899ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T18:55:22.915132Z","caller":"traceutil/trace.go:171","msg":"trace[1763067979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24743; }","duration":"313.842058ms","start":"2026-07-17T18:55:22.601284Z","end":"2026-07-17T18:55:22.915126Z","steps":["trace[1763067979] 'agreement among raft nodes before linearized reading' (duration: 313.795196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:22.91515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:22.601279Z","time spent":"313.868628ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-17T18:55:28.466636Z","caller":"traceutil/trace.go:171","msg":"trace[1359747209] transaction","detail":"{read_only:false; response_revision:24780; number_of_response:1; }","duration":"140.379926ms","start":"2026-07-17T18:55:28.326235Z","end":"2026-07-17T18:55:28.466615Z","steps":["trace[1359747209] 'process raft request' (duration: 140.044916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:28.857962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.378946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:28.858045Z","caller":"traceutil/trace.go:171","msg":"trace[1636744158] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:24780; }","duration":"166.517659ms","start":"2026-07-17T18:55:28.691507Z","end":"2026-07-17T18:55:28.858025Z","steps":["trace[1636744158] 'count revisions from in-memory index tree' (duration: 166.244662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:28.858627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.988103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T18:55:28.85879Z","caller":"traceutil/trace.go:171","msg":"trace[224203363] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:24780; }","duration":"167.202959ms","start":"2026-07-17T18:55:28.691555Z","end":"2026-07-17T18:55:28.858758Z","steps":["trace[224203363] 'count revisions from in-memory index tree' (duration: 166.891219ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:28.901128Z","caller":"traceutil/trace.go:171","msg":"trace[1133983407] transaction","detail":"{read_only:false; response_revision:24781; number_of_response:1; }","duration":"157.278724ms","start":"2026-07-17T18:55:28.743818Z","end":"2026-07-17T18:55:28.901097Z","steps":["trace[1133983407] 'process raft request' (duration: 157.113519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:29.147775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.059033ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:29.147903Z","caller":"traceutil/trace.go:171","msg":"trace[646342933] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24781; }","duration":"168.193916ms","start":"2026-07-17T18:55:28.979687Z","end":"2026-07-17T18:55:29.147881Z","steps":["trace[646342933] 'range keys from in-memory index tree' (duration: 168.043821ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:29.147984Z","caller":"traceutil/trace.go:171","msg":"trace[2068159548] transaction","detail":"{read_only:false; response_revision:24782; number_of_response:1; }","duration":"212.739669ms","start":"2026-07-17T18:55:28.935209Z","end":"2026-07-17T18:55:29.147948Z","steps":["trace[2068159548] 'process raft request' (duration: 130.680728ms)","trace[2068159548] 'compare' (duration: 81.855675ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:29.148159Z","caller":"traceutil/trace.go:171","msg":"trace[176613627] transaction","detail":"{read_only:false; response_revision:24783; number_of_response:1; }","duration":"212.916444ms","start":"2026-07-17T18:55:28.935229Z","end":"2026-07-17T18:55:29.148145Z","steps":["trace[176613627] 'process raft request' (duration: 212.650136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:29.37014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.587101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T18:55:29.370259Z","caller":"traceutil/trace.go:171","msg":"trace[2113915671] linearizableReadLoop","detail":"{readStateIndex:25594; appliedIndex:25591; }","duration":"419.74077ms","start":"2026-07-17T18:55:28.9505Z","end":"2026-07-17T18:55:29.370241Z","steps":["trace[2113915671] 'read index received' (duration: 115.374504ms)","trace[2113915671] 'applied index is now lower than readState.Index' (duration: 304.364676ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:29.370283Z","caller":"traceutil/trace.go:171","msg":"trace[461547408] transaction","detail":"{read_only:false; response_revision:24784; number_of_response:1; }","duration":"434.881228ms","start":"2026-07-17T18:55:28.935376Z","end":"2026-07-17T18:55:29.370257Z","steps":["trace[461547408] 'process raft request' (duration: 315.058721ms)","trace[461547408] 'compare' (duration: 119.450987ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:55:29.370393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:28.935345Z","time spent":"434.989412ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:55:29.370408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.903544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-17T18:55:29.370454Z","caller":"traceutil/trace.go:171","msg":"trace[103500044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24784; }","duration":"419.977477ms","start":"2026-07-17T18:55:28.950467Z","end":"2026-07-17T18:55:29.370444Z","steps":["trace[103500044] 'agreement among raft nodes before linearized reading' (duration: 419.841823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:29.370497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:28.950454Z","time spent":"420.033038ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T18:55:29.376037Z","caller":"traceutil/trace.go:171","msg":"trace[1764819964] transaction","detail":"{read_only:false; response_revision:24785; number_of_response:1; }","duration":"106.763095ms","start":"2026-07-17T18:55:29.269237Z","end":"2026-07-17T18:55:29.376Z","steps":["trace[1764819964] 'process raft request' (duration: 106.57591ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:29.55986Z","caller":"traceutil/trace.go:171","msg":"trace[1630462910] transaction","detail":"{read_only:false; response_revision:24786; number_of_response:1; }","duration":"289.435972ms","start":"2026-07-17T18:55:29.27039Z","end":"2026-07-17T18:55:29.559826Z","steps":["trace[1630462910] 'process raft request' (duration: 277.577834ms)","trace[1630462910] 'compare' (duration: 11.659612ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:29.559991Z","caller":"traceutil/trace.go:171","msg":"trace[299444649] transaction","detail":"{read_only:false; response_revision:24787; number_of_response:1; }","duration":"235.582712ms","start":"2026-07-17T18:55:29.324388Z","end":"2026-07-17T18:55:29.559971Z","steps":["trace[299444649] 'process raft request' (duration: 235.390957ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:29.560153Z","caller":"traceutil/trace.go:171","msg":"trace[2007910623] linearizableReadLoop","detail":"{readStateIndex:25597; appliedIndex:25594; }","duration":"189.858956ms","start":"2026-07-17T18:55:29.370285Z","end":"2026-07-17T18:55:29.560144Z","steps":["trace[2007910623] 'read index received' (duration: 5.41881ms)","trace[2007910623] 'applied index is now lower than readState.Index' (duration: 184.439646ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:29.560194Z","caller":"traceutil/trace.go:171","msg":"trace[942344596] transaction","detail":"{read_only:false; response_revision:24788; number_of_response:1; }","duration":"186.145543ms","start":"2026-07-17T18:55:29.374045Z","end":"2026-07-17T18:55:29.56019Z","steps":["trace[942344596] 'process raft request' (duration: 185.883946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:29.560318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.978773ms","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-07-17T18:55:29.560339Z","caller":"traceutil/trace.go:171","msg":"trace[1526339518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24788; }","duration":"201.042685ms","start":"2026-07-17T18:55:29.35929Z","end":"2026-07-17T18:55:29.560333Z","steps":["trace[1526339518] 'agreement among raft nodes before linearized reading' (duration: 200.942792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:29.560413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.220044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-17T18:55:29.560391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.122967ms","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-07-17T18:55:29.560429Z","caller":"traceutil/trace.go:171","msg":"trace[1272150212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24788; }","duration":"605.257034ms","start":"2026-07-17T18:55:28.955168Z","end":"2026-07-17T18:55:29.560425Z","steps":["trace[1272150212] 'agreement among raft nodes before linearized reading' (duration: 605.209223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:29.560443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:28.955156Z","time spent":"605.282635ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T18:55:29.560448Z","caller":"traceutil/trace.go:171","msg":"trace[781668656] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24788; }","duration":"417.24186ms","start":"2026-07-17T18:55:29.143192Z","end":"2026-07-17T18:55:29.560434Z","steps":["trace[781668656] 'agreement among raft nodes before linearized reading' (duration: 417.133077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:29.560473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:29.143155Z","time spent":"417.312902ms","remote":"127.0.0.1:32924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-17T18:55:29.560471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.819578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:29.560529Z","caller":"traceutil/trace.go:171","msg":"trace[1297364951] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24788; }","duration":"170.885881ms","start":"2026-07-17T18:55:29.389626Z","end":"2026-07-17T18:55:29.560512Z","steps":["trace[1297364951] 'agreement among raft nodes before linearized reading' (duration: 170.776888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:29.560567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.20382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-17T18:55:29.560639Z","caller":"traceutil/trace.go:171","msg":"trace[395260362] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24788; }","duration":"598.298042ms","start":"2026-07-17T18:55:28.962323Z","end":"2026-07-17T18:55:29.560621Z","steps":["trace[395260362] 'agreement among raft nodes before linearized reading' (duration: 598.000354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:29.560655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.174694ms","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-07-17T18:55:29.560692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:28.962316Z","time spent":"598.360743ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-17T18:55:29.560743Z","caller":"traceutil/trace.go:171","msg":"trace[1699649668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24788; }","duration":"604.287478ms","start":"2026-07-17T18:55:28.956438Z","end":"2026-07-17T18:55:29.560725Z","steps":["trace[1699649668] 'agreement among raft nodes before linearized reading' (duration: 603.869566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:29.560797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:28.956397Z","time spent":"604.38764ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T18:55:29.892129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.387903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T18:55:29.892316Z","caller":"traceutil/trace.go:171","msg":"trace[939083430] transaction","detail":"{read_only:false; response_revision:24789; number_of_response:1; }","duration":"326.608861ms","start":"2026-07-17T18:55:29.565683Z","end":"2026-07-17T18:55:29.892292Z","steps":["trace[939083430] 'process raft request' (duration: 132.976151ms)","trace[939083430] 'compare' (duration: 193.038474ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:29.892407Z","caller":"traceutil/trace.go:171","msg":"trace[31393955] transaction","detail":"{read_only:false; response_revision:24791; number_of_response:1; }","duration":"326.17444ms","start":"2026-07-17T18:55:29.56621Z","end":"2026-07-17T18:55:29.892385Z","steps":["trace[31393955] 'process raft request' (duration: 326.064707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:29.892452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:29.565662Z","time spent":"326.715184ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:55:29.892531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:29.56618Z","time spent":"326.285882ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:29.892555Z","caller":"traceutil/trace.go:171","msg":"trace[1824350079] transaction","detail":"{read_only:false; response_revision:24790; number_of_response:1; }","duration":"326.289122ms","start":"2026-07-17T18:55:29.566189Z","end":"2026-07-17T18:55:29.892478Z","steps":["trace[1824350079] 'process raft request' (duration: 326.033055ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:29.892549Z","caller":"traceutil/trace.go:171","msg":"trace[634359476] transaction","detail":"{read_only:false; response_revision:24792; number_of_response:1; }","duration":"325.928152ms","start":"2026-07-17T18:55:29.566578Z","end":"2026-07-17T18:55:29.892506Z","steps":["trace[634359476] 'process raft request' (duration: 325.727156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:29.892707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:29.566162Z","time spent":"326.475168ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:55:29.89286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:29.566563Z","time spent":"326.20159ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:30.069478Z","caller":"traceutil/trace.go:171","msg":"trace[1997791759] transaction","detail":"{read_only:false; response_revision:24793; number_of_response:1; }","duration":"169.503922ms","start":"2026-07-17T18:55:29.89993Z","end":"2026-07-17T18:55:30.069434Z","steps":["trace[1997791759] 'process raft request' (duration: 147.130973ms)","trace[1997791759] 'compare' (duration: 21.975678ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:55:30.452455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.26124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-07-17T18:55:30.452618Z","caller":"traceutil/trace.go:171","msg":"trace[674034000] transaction","detail":"{read_only:false; response_revision:24794; number_of_response:1; }","duration":"363.367548ms","start":"2026-07-17T18:55:30.089222Z","end":"2026-07-17T18:55:30.45259Z","steps":["trace[674034000] 'process raft request' (duration: 199.718698ms)","trace[674034000] 'compare' (duration: 163.117786ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:55:30.452688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:30.089202Z","time spent":"363.457201ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1633,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T18:55:30.898069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.753594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T18:55:30.898327Z","caller":"traceutil/trace.go:171","msg":"trace[1258996782] transaction","detail":"{read_only:false; response_revision:24796; number_of_response:1; }","duration":"427.412012ms","start":"2026-07-17T18:55:30.470884Z","end":"2026-07-17T18:55:30.898296Z","steps":["trace[1258996782] 'process raft request' (duration: 427.315939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:30.898624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:30.470863Z","time spent":"427.539566ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:30.898726Z","caller":"traceutil/trace.go:171","msg":"trace[1243122772] transaction","detail":"{read_only:false; response_revision:24795; number_of_response:1; }","duration":"433.817549ms","start":"2026-07-17T18:55:30.464875Z","end":"2026-07-17T18:55:30.898693Z","steps":["trace[1243122772] 'process raft request' (duration: 123.295143ms)","trace[1243122772] 'compare' (duration: 309.421415ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:55:30.899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:30.464858Z","time spent":"433.913812ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1946,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:55:31.3108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.173068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T18:55:31.310942Z","caller":"traceutil/trace.go:171","msg":"trace[1520592688] transaction","detail":"{read_only:false; response_revision:24797; number_of_response:1; }","duration":"596.850411ms","start":"2026-07-17T18:55:30.71406Z","end":"2026-07-17T18:55:31.310911Z","steps":["trace[1520592688] 'process raft request' (duration: 373.438007ms)","trace[1520592688] 'compare' (duration: 222.428157ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:31.311029Z","caller":"traceutil/trace.go:171","msg":"trace[1126946686] linearizableReadLoop","detail":"{readStateIndex:25607; appliedIndex:25604; }","duration":"533.118018ms","start":"2026-07-17T18:55:30.777873Z","end":"2026-07-17T18:55:31.310991Z","steps":["trace[1126946686] 'read index received' (duration: 93.5813ms)","trace[1126946686] 'applied index is now lower than readState.Index' (duration: 439.530307ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:31.311077Z","caller":"traceutil/trace.go:171","msg":"trace[694993757] transaction","detail":"{read_only:false; response_revision:24798; number_of_response:1; }","duration":"405.973858ms","start":"2026-07-17T18:55:30.905079Z","end":"2026-07-17T18:55:31.311053Z","steps":["trace[694993757] 'process raft request' (duration: 405.831224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:31.311108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:30.714035Z","time spent":"596.976265ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:55:31.311146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:30.905061Z","time spent":"406.053ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:31.311245Z","caller":"traceutil/trace.go:171","msg":"trace[1874357776] transaction","detail":"{read_only:false; response_revision:24799; number_of_response:1; }","duration":"403.774248ms","start":"2026-07-17T18:55:30.907437Z","end":"2026-07-17T18:55:31.311211Z","steps":["trace[1874357776] 'process raft request' (duration: 403.559692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:31.311316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.419666ms","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-07-17T18:55:31.311392Z","caller":"traceutil/trace.go:171","msg":"trace[1372105908] 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:24799; }","duration":"533.535339ms","start":"2026-07-17T18:55:30.777836Z","end":"2026-07-17T18:55:31.311371Z","steps":["trace[1372105908] 'agreement among raft nodes before linearized reading' (duration: 533.303243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:31.311412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:30.90742Z","time spent":"403.910271ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2793,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:55:31.311446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:30.777819Z","time spent":"533.613352ms","remote":"127.0.0.1:36724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-07-17T18:55:31.490954Z","caller":"traceutil/trace.go:171","msg":"trace[1792129091] linearizableReadLoop","detail":"{readStateIndex:25611; appliedIndex:25609; }","duration":"179.829058ms","start":"2026-07-17T18:55:31.3111Z","end":"2026-07-17T18:55:31.490929Z","steps":["trace[1792129091] 'read index received' (duration: 167.6124ms)","trace[1792129091] 'applied index is now lower than readState.Index' (duration: 12.215248ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:31.491066Z","caller":"traceutil/trace.go:171","msg":"trace[430156436] transaction","detail":"{read_only:false; response_revision:24801; number_of_response:1; }","duration":"337.436261ms","start":"2026-07-17T18:55:31.153615Z","end":"2026-07-17T18:55:31.491051Z","steps":["trace[430156436] 'process raft request' (duration: 337.255946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:31.491105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.36217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:31.491143Z","caller":"traceutil/trace.go:171","msg":"trace[1399055843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24801; }","duration":"479.414381ms","start":"2026-07-17T18:55:31.011719Z","end":"2026-07-17T18:55:31.491134Z","steps":["trace[1399055843] 'agreement among raft nodes before linearized reading' (duration: 479.335629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:31.491237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:31.011677Z","time spent":"479.551475ms","remote":"127.0.0.1:46148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T18:55:31.491145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:31.153587Z","time spent":"337.508812ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:31.491296Z","caller":"traceutil/trace.go:171","msg":"trace[171151209] transaction","detail":"{read_only:false; response_revision:24800; number_of_response:1; }","duration":"337.38324ms","start":"2026-07-17T18:55:31.153611Z","end":"2026-07-17T18:55:31.490994Z","steps":["trace[171151209] 'process raft request' (duration: 325.09194ms)","trace[171151209] 'compare' (duration: 11.985071ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:55:31.491613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:31.153587Z","time spent":"337.929304ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:55:31.749749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.989841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T18:55:31.749991Z","caller":"traceutil/trace.go:171","msg":"trace[1485785974] linearizableReadLoop","detail":"{readStateIndex:25613; appliedIndex:25611; }","duration":"258.947239ms","start":"2026-07-17T18:55:31.49102Z","end":"2026-07-17T18:55:31.749967Z","steps":["trace[1485785974] 'read index received' (duration: 125.512315ms)","trace[1485785974] 'applied index is now lower than readState.Index' (duration: 133.433304ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:31.750041Z","caller":"traceutil/trace.go:171","msg":"trace[1743382890] transaction","detail":"{read_only:false; response_revision:24802; number_of_response:1; }","duration":"373.262663ms","start":"2026-07-17T18:55:31.37671Z","end":"2026-07-17T18:55:31.749972Z","steps":["trace[1743382890] 'process raft request' (duration: 239.904701ms)","trace[1743382890] 'compare' (duration: 132.826357ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:55:31.750148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.582862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:31.750164Z","caller":"traceutil/trace.go:171","msg":"trace[1101906075] transaction","detail":"{read_only:false; response_revision:24803; number_of_response:1; }","duration":"368.426379ms","start":"2026-07-17T18:55:31.381723Z","end":"2026-07-17T18:55:31.750149Z","steps":["trace[1101906075] 'process raft request' (duration: 368.157211ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:31.750184Z","caller":"traceutil/trace.go:171","msg":"trace[1002180580] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24803; }","duration":"433.657964ms","start":"2026-07-17T18:55:31.316511Z","end":"2026-07-17T18:55:31.750169Z","steps":["trace[1002180580] 'agreement among raft nodes before linearized reading' (duration: 433.590202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:31.750183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.635864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-07-17T18:55:31.750234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:31.316486Z","time spent":"433.737937ms","remote":"127.0.0.1:46348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-07-17T18:55:31.750217Z","caller":"traceutil/trace.go:171","msg":"trace[1427774538] transaction","detail":"{read_only:false; response_revision:24805; number_of_response:1; }","duration":"182.244915ms","start":"2026-07-17T18:55:31.567944Z","end":"2026-07-17T18:55:31.750189Z","steps":["trace[1427774538] 'process raft request' (duration: 182.197904ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:31.750265Z","caller":"traceutil/trace.go:171","msg":"trace[1042410272] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:24803; }","duration":"429.991683ms","start":"2026-07-17T18:55:31.320255Z","end":"2026-07-17T18:55:31.750247Z","steps":["trace[1042410272] 'agreement among raft nodes before linearized reading' (duration: 429.753247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:31.750283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:31.381705Z","time spent":"368.498351ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:31.750416Z","caller":"traceutil/trace.go:171","msg":"trace[1956388062] transaction","detail":"{read_only:false; response_revision:24804; number_of_response:1; }","duration":"182.663997ms","start":"2026-07-17T18:55:31.567742Z","end":"2026-07-17T18:55:31.750406Z","steps":["trace[1956388062] 'process raft request' (duration: 182.262376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:31.750412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.173586ms","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-07-17T18:55:31.75044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.137329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-17T18:55:31.750476Z","caller":"traceutil/trace.go:171","msg":"trace[182291836] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24805; }","duration":"103.285129ms","start":"2026-07-17T18:55:31.647175Z","end":"2026-07-17T18:55:31.75046Z","steps":["trace[182291836] 'agreement among raft nodes before linearized reading' (duration: 103.047203ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:31.750488Z","caller":"traceutil/trace.go:171","msg":"trace[1343822850] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24805; }","duration":"162.216051ms","start":"2026-07-17T18:55:31.588264Z","end":"2026-07-17T18:55:31.75048Z","steps":["trace[1343822850] 'agreement among raft nodes before linearized reading' (duration: 162.104598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:31.750485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.702454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-17T18:55:31.750339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:31.320244Z","time spent":"430.062985ms","remote":"127.0.0.1:46200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/199.204.45.205\" "} {"level":"info","ts":"2026-07-17T18:55:31.750551Z","caller":"traceutil/trace.go:171","msg":"trace[47939494] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:0; response_revision:24805; }","duration":"434.776246ms","start":"2026-07-17T18:55:31.31574Z","end":"2026-07-17T18:55:31.750516Z","steps":["trace[47939494] 'agreement among raft nodes before linearized reading' (duration: 434.672543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:31.750302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:31.376686Z","time spent":"373.474669ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:55:31.750592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:31.315724Z","time spent":"434.853978ms","remote":"127.0.0.1:46258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"warn","ts":"2026-07-17T18:55:31.750682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.833027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-17T18:55:31.750768Z","caller":"traceutil/trace.go:171","msg":"trace[1370149578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24805; }","duration":"184.93095ms","start":"2026-07-17T18:55:31.565791Z","end":"2026-07-17T18:55:31.750722Z","steps":["trace[1370149578] 'agreement among raft nodes before linearized reading' (duration: 184.731664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:31.750954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.381732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:31.75102Z","caller":"traceutil/trace.go:171","msg":"trace[1633572755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24805; }","duration":"258.523406ms","start":"2026-07-17T18:55:31.492478Z","end":"2026-07-17T18:55:31.751001Z","steps":["trace[1633572755] 'agreement among raft nodes before linearized reading' (duration: 258.390483ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:32.181998Z","caller":"traceutil/trace.go:171","msg":"trace[1655150050] linearizableReadLoop","detail":"{readStateIndex:25624; appliedIndex:25623; }","duration":"342.433339ms","start":"2026-07-17T18:55:31.839536Z","end":"2026-07-17T18:55:32.181969Z","steps":["trace[1655150050] 'read index received' (duration: 342.059339ms)","trace[1655150050] 'applied index is now lower than readState.Index' (duration: 372.9µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:32.182035Z","caller":"traceutil/trace.go:171","msg":"trace[1709622401] transaction","detail":"{read_only:false; response_revision:24813; number_of_response:1; }","duration":"358.216856ms","start":"2026-07-17T18:55:31.823781Z","end":"2026-07-17T18:55:32.181998Z","steps":["trace[1709622401] 'process raft request' (duration: 357.972399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:32.182196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:31.823753Z","time spent":"358.3553ms","remote":"127.0.0.1:46224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":130537,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:55:32.182213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.774624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T18:55:32.182258Z","caller":"traceutil/trace.go:171","msg":"trace[1436598835] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:24813; }","duration":"314.903037ms","start":"2026-07-17T18:55:31.867339Z","end":"2026-07-17T18:55:32.182242Z","steps":["trace[1436598835] 'agreement among raft nodes before linearized reading' (duration: 314.797144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:32.182261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.811401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-17T18:55:32.182306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:31.867296Z","time spent":"314.99792ms","remote":"127.0.0.1:58594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"warn","ts":"2026-07-17T18:55:32.182309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.843025ms","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-07-17T18:55:32.182352Z","caller":"traceutil/trace.go:171","msg":"trace[428191968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24813; }","duration":"169.944034ms","start":"2026-07-17T18:55:32.012382Z","end":"2026-07-17T18:55:32.182326Z","steps":["trace[428191968] 'agreement among raft nodes before linearized reading' (duration: 169.807751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:32.182339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.326419ms","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-07-17T18:55:32.182435Z","caller":"traceutil/trace.go:171","msg":"trace[1816163603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24813; }","duration":"285.495323ms","start":"2026-07-17T18:55:31.89692Z","end":"2026-07-17T18:55:32.182415Z","steps":["trace[1816163603] 'agreement among raft nodes before linearized reading' (duration: 285.241316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:32.182465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.914647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-17T18:55:32.182498Z","caller":"traceutil/trace.go:171","msg":"trace[790145783] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24813; }","duration":"284.948348ms","start":"2026-07-17T18:55:31.897539Z","end":"2026-07-17T18:55:32.182487Z","steps":["trace[790145783] 'agreement among raft nodes before linearized reading' (duration: 284.851505ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:32.182367Z","caller":"traceutil/trace.go:171","msg":"trace[1104883248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24813; }","duration":"284.918767ms","start":"2026-07-17T18:55:31.897435Z","end":"2026-07-17T18:55:32.182354Z","steps":["trace[1104883248] 'agreement among raft nodes before linearized reading' (duration: 284.731282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:32.182603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.766111ms","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-07-17T18:55:32.182644Z","caller":"traceutil/trace.go:171","msg":"trace[1983275908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24813; }","duration":"285.841163ms","start":"2026-07-17T18:55:31.896793Z","end":"2026-07-17T18:55:32.182634Z","steps":["trace[1983275908] 'agreement among raft nodes before linearized reading' (duration: 285.73635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:32.182946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.397916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:32.183032Z","caller":"traceutil/trace.go:171","msg":"trace[1681693401] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24813; }","duration":"343.51729ms","start":"2026-07-17T18:55:31.839466Z","end":"2026-07-17T18:55:32.182984Z","steps":["trace[1681693401] 'agreement among raft nodes before linearized reading' (duration: 342.611884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:32.18307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:31.839364Z","time spent":"343.698275ms","remote":"127.0.0.1:46622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-17T18:55:32.370528Z","caller":"traceutil/trace.go:171","msg":"trace[1026854029] transaction","detail":"{read_only:false; response_revision:24814; number_of_response:1; }","duration":"186.684466ms","start":"2026-07-17T18:55:32.183818Z","end":"2026-07-17T18:55:32.370503Z","steps":["trace[1026854029] 'process raft request' (duration: 186.549613ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:32.371549Z","caller":"traceutil/trace.go:171","msg":"trace[874277267] linearizableReadLoop","detail":"{readStateIndex:25628; appliedIndex:25624; }","duration":"184.985021ms","start":"2026-07-17T18:55:32.186543Z","end":"2026-07-17T18:55:32.371528Z","steps":["trace[874277267] 'read index received' (duration: 183.915011ms)","trace[874277267] 'applied index is now lower than readState.Index' (duration: 1.06919ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:55:32.371874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.30716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:32.371888Z","caller":"traceutil/trace.go:171","msg":"trace[845489645] transaction","detail":"{read_only:false; response_revision:24816; number_of_response:1; }","duration":"185.360231ms","start":"2026-07-17T18:55:32.186406Z","end":"2026-07-17T18:55:32.371766Z","steps":["trace[845489645] 'process raft request' (duration: 185.015162ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:32.371928Z","caller":"traceutil/trace.go:171","msg":"trace[1216383511] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24818; }","duration":"185.417492ms","start":"2026-07-17T18:55:32.186495Z","end":"2026-07-17T18:55:32.371912Z","steps":["trace[1216383511] 'agreement among raft nodes before linearized reading' (duration: 185.298809ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:32.372227Z","caller":"traceutil/trace.go:171","msg":"trace[1224528718] transaction","detail":"{read_only:false; response_revision:24817; number_of_response:1; }","duration":"185.795883ms","start":"2026-07-17T18:55:32.186403Z","end":"2026-07-17T18:55:32.372199Z","steps":["trace[1224528718] 'process raft request' (duration: 185.054703ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:32.372305Z","caller":"traceutil/trace.go:171","msg":"trace[1386281546] transaction","detail":"{read_only:false; response_revision:24815; number_of_response:1; }","duration":"186.003189ms","start":"2026-07-17T18:55:32.186279Z","end":"2026-07-17T18:55:32.372282Z","steps":["trace[1386281546] 'process raft request' (duration: 185.011521ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:32.372456Z","caller":"traceutil/trace.go:171","msg":"trace[1940892804] transaction","detail":"{read_only:false; response_revision:24818; number_of_response:1; }","duration":"184.750344ms","start":"2026-07-17T18:55:32.187685Z","end":"2026-07-17T18:55:32.372435Z","steps":["trace[1940892804] 'process raft request' (duration: 183.811028ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:33.676693Z","caller":"traceutil/trace.go:171","msg":"trace[986706950] transaction","detail":"{read_only:false; response_revision:24872; number_of_response:1; }","duration":"179.709485ms","start":"2026-07-17T18:55:33.496956Z","end":"2026-07-17T18:55:33.676666Z","steps":["trace[986706950] 'process raft request' (duration: 179.568511ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:33.699502Z","caller":"traceutil/trace.go:171","msg":"trace[1956581446] linearizableReadLoop","detail":"{readStateIndex:25684; appliedIndex:25682; }","duration":"174.966194ms","start":"2026-07-17T18:55:33.524503Z","end":"2026-07-17T18:55:33.699469Z","steps":["trace[1956581446] 'read index received' (duration: 152.07646ms)","trace[1956581446] 'applied index is now lower than readState.Index' (duration: 22.888484ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:33.699543Z","caller":"traceutil/trace.go:171","msg":"trace[471681129] transaction","detail":"{read_only:false; response_revision:24873; number_of_response:1; }","duration":"202.528286ms","start":"2026-07-17T18:55:33.496994Z","end":"2026-07-17T18:55:33.699522Z","steps":["trace[471681129] 'process raft request' (duration: 202.30576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:33.699779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.412233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:33.699901Z","caller":"traceutil/trace.go:171","msg":"trace[500242234] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24873; }","duration":"146.591768ms","start":"2026-07-17T18:55:33.553283Z","end":"2026-07-17T18:55:33.699875Z","steps":["trace[500242234] 'agreement among raft nodes before linearized reading' (duration: 146.383062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:33.699803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.265412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f3dc46eb-124a-4b60-92fc-3471ef79c57c\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-17T18:55:33.700059Z","caller":"traceutil/trace.go:171","msg":"trace[462703643] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f3dc46eb-124a-4b60-92fc-3471ef79c57c; range_end:; response_count:1; response_revision:24873; }","duration":"175.532429ms","start":"2026-07-17T18:55:33.524497Z","end":"2026-07-17T18:55:33.700029Z","steps":["trace[462703643] 'agreement among raft nodes before linearized reading' (duration: 175.130308ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:33.88222Z","caller":"traceutil/trace.go:171","msg":"trace[620555751] transaction","detail":"{read_only:false; response_revision:24874; number_of_response:1; }","duration":"120.566437ms","start":"2026-07-17T18:55:33.76163Z","end":"2026-07-17T18:55:33.882197Z","steps":["trace[620555751] 'process raft request' (duration: 119.526818ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:33.882446Z","caller":"traceutil/trace.go:171","msg":"trace[937277864] transaction","detail":"{read_only:false; response_revision:24875; number_of_response:1; }","duration":"120.333851ms","start":"2026-07-17T18:55:33.762085Z","end":"2026-07-17T18:55:33.882419Z","steps":["trace[937277864] 'process raft request' (duration: 120.156096ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:33.88252Z","caller":"traceutil/trace.go:171","msg":"trace[1674471884] transaction","detail":"{read_only:false; response_revision:24876; number_of_response:1; }","duration":"120.239698ms","start":"2026-07-17T18:55:33.762261Z","end":"2026-07-17T18:55:33.882501Z","steps":["trace[1674471884] 'process raft request' (duration: 120.116585ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:33.882584Z","caller":"traceutil/trace.go:171","msg":"trace[1952305471] linearizableReadLoop","detail":"{readStateIndex:25688; appliedIndex:25684; }","duration":"110.452798ms","start":"2026-07-17T18:55:33.772126Z","end":"2026-07-17T18:55:33.882578Z","steps":["trace[1952305471] 'read index received' (duration: 109.046849ms)","trace[1952305471] 'applied index is now lower than readState.Index' (duration: 1.405369ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:33.882694Z","caller":"traceutil/trace.go:171","msg":"trace[2030055922] transaction","detail":"{read_only:false; response_revision:24877; number_of_response:1; }","duration":"120.092484ms","start":"2026-07-17T18:55:33.762575Z","end":"2026-07-17T18:55:33.882667Z","steps":["trace[2030055922] 'process raft request' (duration: 119.890648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:33.883561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.424714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-17T18:55:33.883626Z","caller":"traceutil/trace.go:171","msg":"trace[147794804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24877; }","duration":"111.528877ms","start":"2026-07-17T18:55:33.772082Z","end":"2026-07-17T18:55:33.883611Z","steps":["trace[147794804] 'agreement among raft nodes before linearized reading' (duration: 110.54432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:36.105886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.573017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:36.105977Z","caller":"traceutil/trace.go:171","msg":"trace[858955561] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:24893; }","duration":"116.70006ms","start":"2026-07-17T18:55:35.989255Z","end":"2026-07-17T18:55:36.105956Z","steps":["trace[858955561] 'count revisions from in-memory index tree' (duration: 116.478245ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:36.737346Z","caller":"traceutil/trace.go:171","msg":"trace[315354146] transaction","detail":"{read_only:false; response_revision:24896; number_of_response:1; }","duration":"346.194633ms","start":"2026-07-17T18:55:36.391133Z","end":"2026-07-17T18:55:36.737328Z","steps":["trace[315354146] 'process raft request' (duration: 250.534365ms)","trace[315354146] 'compare' (duration: 95.220326ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:55:36.737439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:36.39112Z","time spent":"346.270506ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:36.737531Z","caller":"traceutil/trace.go:171","msg":"trace[817325675] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"345.273078ms","start":"2026-07-17T18:55:36.392239Z","end":"2026-07-17T18:55:36.737512Z","steps":["trace[817325675] 'process raft request' (duration: 345.038612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:36.737614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:36.392231Z","time spent":"345.34423ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:36.737934Z","caller":"traceutil/trace.go:171","msg":"trace[1380444158] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"344.885848ms","start":"2026-07-17T18:55:36.393037Z","end":"2026-07-17T18:55:36.737923Z","steps":["trace[1380444158] 'process raft request' (duration: 344.422205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:36.738003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:36.393019Z","time spent":"344.950159ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:37.040263Z","caller":"traceutil/trace.go:171","msg":"trace[574329394] transaction","detail":"{read_only:false; response_revision:24899; number_of_response:1; }","duration":"122.747308ms","start":"2026-07-17T18:55:36.917491Z","end":"2026-07-17T18:55:37.040238Z","steps":["trace[574329394] 'process raft request' (duration: 122.265085ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:37.938437Z","caller":"traceutil/trace.go:171","msg":"trace[565203317] transaction","detail":"{read_only:false; response_revision:24904; number_of_response:1; }","duration":"309.022365ms","start":"2026-07-17T18:55:37.629385Z","end":"2026-07-17T18:55:37.938407Z","steps":["trace[565203317] 'process raft request' (duration: 246.815602ms)","trace[565203317] 'compare' (duration: 61.693618ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:55:37.938579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:37.629357Z","time spent":"309.149938ms","remote":"127.0.0.1:46242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:55:37.971027Z","caller":"traceutil/trace.go:171","msg":"trace[674201584] linearizableReadLoop","detail":"{readStateIndex:25718; appliedIndex:25715; }","duration":"218.098858ms","start":"2026-07-17T18:55:37.752904Z","end":"2026-07-17T18:55:37.971003Z","steps":["trace[674201584] 'read index received' (duration: 123.318574ms)","trace[674201584] 'applied index is now lower than readState.Index' (duration: 94.779173ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:37.971117Z","caller":"traceutil/trace.go:171","msg":"trace[1064249112] transaction","detail":"{read_only:false; response_revision:24906; number_of_response:1; }","duration":"260.732198ms","start":"2026-07-17T18:55:37.710354Z","end":"2026-07-17T18:55:37.971086Z","steps":["trace[1064249112] 'process raft request' (duration: 260.623685ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:37.971134Z","caller":"traceutil/trace.go:171","msg":"trace[13705529] transaction","detail":"{read_only:false; response_revision:24905; number_of_response:1; }","duration":"278.182791ms","start":"2026-07-17T18:55:37.692896Z","end":"2026-07-17T18:55:37.971079Z","steps":["trace[13705529] 'process raft request' (duration: 277.973795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:37.971269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.348984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f3dc46eb-124a-4b60-92fc-3471ef79c57c\" ","response":"range_response_count:1 size:3950"} {"level":"info","ts":"2026-07-17T18:55:37.971359Z","caller":"traceutil/trace.go:171","msg":"trace[1334900425] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f3dc46eb-124a-4b60-92fc-3471ef79c57c; range_end:; response_count:1; response_revision:24906; }","duration":"218.489288ms","start":"2026-07-17T18:55:37.752854Z","end":"2026-07-17T18:55:37.971343Z","steps":["trace[1334900425] 'agreement among raft nodes before linearized reading' (duration: 218.259012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:37.972321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.479235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:37.972367Z","caller":"traceutil/trace.go:171","msg":"trace[426493662] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:24910; }","duration":"133.541847ms","start":"2026-07-17T18:55:37.838815Z","end":"2026-07-17T18:55:37.972357Z","steps":["trace[426493662] 'agreement among raft nodes before linearized reading' (duration: 133.449225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:37.972912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.57575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f3dc46eb-124a-4b60-92fc-3471ef79c57c\" ","response":"range_response_count:1 size:3950"} {"level":"info","ts":"2026-07-17T18:55:37.972951Z","caller":"traceutil/trace.go:171","msg":"trace[1906872662] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f3dc46eb-124a-4b60-92fc-3471ef79c57c; range_end:; response_count:1; response_revision:24910; }","duration":"106.667762ms","start":"2026-07-17T18:55:37.866276Z","end":"2026-07-17T18:55:37.972944Z","steps":["trace[1906872662] 'agreement among raft nodes before linearized reading' (duration: 106.59299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:41.594519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.512837ms","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-07-17T18:55:41.594634Z","caller":"traceutil/trace.go:171","msg":"trace[1897337123] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:24953; }","duration":"111.656711ms","start":"2026-07-17T18:55:41.48295Z","end":"2026-07-17T18:55:41.594607Z","steps":["trace[1897337123] 'count revisions from in-memory index tree' (duration: 111.453765ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:42.199952Z","caller":"traceutil/trace.go:171","msg":"trace[2075722528] transaction","detail":"{read_only:false; response_revision:24955; number_of_response:1; }","duration":"214.917619ms","start":"2026-07-17T18:55:41.98501Z","end":"2026-07-17T18:55:42.199928Z","steps":["trace[2075722528] 'process raft request' (duration: 166.373985ms)","trace[2075722528] 'compare' (duration: 48.39515ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:42.200472Z","caller":"traceutil/trace.go:171","msg":"trace[894028523] transaction","detail":"{read_only:false; response_revision:24956; number_of_response:1; }","duration":"214.784845ms","start":"2026-07-17T18:55:41.985652Z","end":"2026-07-17T18:55:42.200437Z","steps":["trace[894028523] 'process raft request' (duration: 214.24459ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:42.200876Z","caller":"traceutil/trace.go:171","msg":"trace[691291010] linearizableReadLoop","detail":"{readStateIndex:25773; appliedIndex:25767; }","duration":"203.617277ms","start":"2026-07-17T18:55:41.997229Z","end":"2026-07-17T18:55:42.200847Z","steps":["trace[691291010] 'read index received' (duration: 52.077841ms)","trace[691291010] 'applied index is now lower than readState.Index' (duration: 151.538205ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:42.201126Z","caller":"traceutil/trace.go:171","msg":"trace[1419009431] transaction","detail":"{read_only:false; response_revision:24959; number_of_response:1; }","duration":"214.252891ms","start":"2026-07-17T18:55:41.986864Z","end":"2026-07-17T18:55:42.201116Z","steps":["trace[1419009431] 'process raft request' (duration: 213.931992ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:42.201163Z","caller":"traceutil/trace.go:171","msg":"trace[831240267] transaction","detail":"{read_only:false; response_revision:24958; number_of_response:1; }","duration":"214.309422ms","start":"2026-07-17T18:55:41.986809Z","end":"2026-07-17T18:55:42.201118Z","steps":["trace[831240267] 'process raft request' (duration: 213.897011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:42.201241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.996517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-17T18:55:42.201277Z","caller":"traceutil/trace.go:171","msg":"trace[1278800204] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24959; }","duration":"204.072569ms","start":"2026-07-17T18:55:41.997194Z","end":"2026-07-17T18:55:42.201267Z","steps":["trace[1278800204] 'agreement among raft nodes before linearized reading' (duration: 203.915825ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:42.201231Z","caller":"traceutil/trace.go:171","msg":"trace[1593171740] transaction","detail":"{read_only:false; response_revision:24957; number_of_response:1; }","duration":"215.531047ms","start":"2026-07-17T18:55:41.985646Z","end":"2026-07-17T18:55:42.201177Z","steps":["trace[1593171740] 'process raft request' (duration: 214.93206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:42.201374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.532392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:42.201402Z","caller":"traceutil/trace.go:171","msg":"trace[1026612110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24959; }","duration":"191.593254ms","start":"2026-07-17T18:55:42.009803Z","end":"2026-07-17T18:55:42.201396Z","steps":["trace[1026612110] 'agreement among raft nodes before linearized reading' (duration: 191.545072ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:53.391444Z","caller":"traceutil/trace.go:171","msg":"trace[1254586610] linearizableReadLoop","detail":"{readStateIndex:25864; appliedIndex:25863; }","duration":"263.635767ms","start":"2026-07-17T18:55:53.127779Z","end":"2026-07-17T18:55:53.391415Z","steps":["trace[1254586610] 'read index received' (duration: 263.498434ms)","trace[1254586610] 'applied index is now lower than readState.Index' (duration: 136.083µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:53.391538Z","caller":"traceutil/trace.go:171","msg":"trace[1748667013] transaction","detail":"{read_only:false; response_revision:25048; number_of_response:1; }","duration":"292.816296ms","start":"2026-07-17T18:55:53.098696Z","end":"2026-07-17T18:55:53.391512Z","steps":["trace[1748667013] 'process raft request' (duration: 292.539948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:53.391764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.950927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-17T18:55:53.39183Z","caller":"traceutil/trace.go:171","msg":"trace[1741479919] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:25048; }","duration":"264.088461ms","start":"2026-07-17T18:55:53.127727Z","end":"2026-07-17T18:55:53.391815Z","steps":["trace[1741479919] 'agreement among raft nodes before linearized reading' (duration: 263.837204ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:53.596462Z","caller":"traceutil/trace.go:171","msg":"trace[624251694] linearizableReadLoop","detail":"{readStateIndex:25865; appliedIndex:25864; }","duration":"204.909603ms","start":"2026-07-17T18:55:53.391522Z","end":"2026-07-17T18:55:53.596431Z","steps":["trace[624251694] 'read index received' (duration: 130.972066ms)","trace[624251694] 'applied index is now lower than readState.Index' (duration: 73.936207ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:53.596473Z","caller":"traceutil/trace.go:171","msg":"trace[1713280702] transaction","detail":"{read_only:false; response_revision:25049; number_of_response:1; }","duration":"221.896923ms","start":"2026-07-17T18:55:53.374544Z","end":"2026-07-17T18:55:53.596441Z","steps":["trace[1713280702] 'process raft request' (duration: 148.008487ms)","trace[1713280702] 'compare' (duration: 73.565797ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:55:53.596637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.923053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T18:55:53.596679Z","caller":"traceutil/trace.go:171","msg":"trace[389742753] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:25049; }","duration":"438.014695ms","start":"2026-07-17T18:55:53.158654Z","end":"2026-07-17T18:55:53.596668Z","steps":["trace[389742753] 'agreement among raft nodes before linearized reading' (duration: 437.881942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:53.596715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:53.158637Z","time spent":"438.067656ms","remote":"127.0.0.1:50490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":37,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-07-17T18:55:53.596681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.879156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:55:53.596813Z","caller":"traceutil/trace.go:171","msg":"trace[1918071333] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25049; }","duration":"397.054471ms","start":"2026-07-17T18:55:53.199732Z","end":"2026-07-17T18:55:53.596786Z","steps":["trace[1918071333] 'agreement among raft nodes before linearized reading' (duration: 396.859355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:53.596883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:55:53.199709Z","time spent":"397.157954ms","remote":"127.0.0.1:36592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":28,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"warn","ts":"2026-07-17T18:55:54.398013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.814039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T18:55:54.398105Z","caller":"traceutil/trace.go:171","msg":"trace[671572071] transaction","detail":"{read_only:false; response_revision:25051; number_of_response:1; }","duration":"156.725498ms","start":"2026-07-17T18:55:54.241361Z","end":"2026-07-17T18:55:54.398086Z","steps":["trace[671572071] 'process raft request' (duration: 24.761225ms)","trace[671572071] 'compare' (duration: 131.708316ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:54.400709Z","caller":"traceutil/trace.go:171","msg":"trace[1075394080] transaction","detail":"{read_only:false; response_revision:25052; number_of_response:1; }","duration":"159.205998ms","start":"2026-07-17T18:55:54.24149Z","end":"2026-07-17T18:55:54.400696Z","steps":["trace[1075394080] 'process raft request' (duration: 159.108805ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:54.401002Z","caller":"traceutil/trace.go:171","msg":"trace[794913255] transaction","detail":"{read_only:false; response_revision:25054; number_of_response:1; }","duration":"157.902521ms","start":"2026-07-17T18:55:54.243088Z","end":"2026-07-17T18:55:54.400991Z","steps":["trace[794913255] 'process raft request' (duration: 157.726956ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:54.401085Z","caller":"traceutil/trace.go:171","msg":"trace[693143840] transaction","detail":"{read_only:false; response_revision:25055; number_of_response:1; }","duration":"157.87509ms","start":"2026-07-17T18:55:54.2432Z","end":"2026-07-17T18:55:54.401075Z","steps":["trace[693143840] 'process raft request' (duration: 157.759117ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:54.401079Z","caller":"traceutil/trace.go:171","msg":"trace[1476225563] linearizableReadLoop","detail":"{readStateIndex:25871; appliedIndex:25867; }","duration":"131.618194ms","start":"2026-07-17T18:55:54.269436Z","end":"2026-07-17T18:55:54.401055Z","steps":["trace[1476225563] 'read index received' (duration: 131.186952ms)","trace[1476225563] 'applied index is now lower than readState.Index' (duration: 430.301µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:55:54.401231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.791798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-17T18:55:54.401257Z","caller":"traceutil/trace.go:171","msg":"trace[1230871756] transaction","detail":"{read_only:false; response_revision:25053; number_of_response:1; }","duration":"159.66151ms","start":"2026-07-17T18:55:54.241579Z","end":"2026-07-17T18:55:54.40124Z","steps":["trace[1230871756] 'process raft request' (duration: 159.087594ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:54.401263Z","caller":"traceutil/trace.go:171","msg":"trace[1424877424] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25055; }","duration":"131.875451ms","start":"2026-07-17T18:55:54.269378Z","end":"2026-07-17T18:55:54.401253Z","steps":["trace[1424877424] 'agreement among raft nodes before linearized reading' (duration: 131.756478ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:54.605929Z","caller":"traceutil/trace.go:171","msg":"trace[1228649563] transaction","detail":"{read_only:false; response_revision:25056; number_of_response:1; }","duration":"202.180557ms","start":"2026-07-17T18:55:54.403716Z","end":"2026-07-17T18:55:54.605896Z","steps":["trace[1228649563] 'process raft request' (duration: 134.413951ms)","trace[1228649563] 'compare' (duration: 67.530959ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:55.059692Z","caller":"traceutil/trace.go:171","msg":"trace[2063090957] linearizableReadLoop","detail":"{readStateIndex:25876; appliedIndex:25874; }","duration":"170.197381ms","start":"2026-07-17T18:55:54.889476Z","end":"2026-07-17T18:55:55.059674Z","steps":["trace[2063090957] 'read index received' (duration: 170.055487ms)","trace[2063090957] 'applied index is now lower than readState.Index' (duration: 141.374µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:55:55.059756Z","caller":"traceutil/trace.go:171","msg":"trace[24579534] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"233.00244ms","start":"2026-07-17T18:55:54.826734Z","end":"2026-07-17T18:55:55.059737Z","steps":["trace[24579534] 'process raft request' (duration: 232.804315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:55:55.059815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.319765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T18:55:55.05984Z","caller":"traceutil/trace.go:171","msg":"trace[1203137543] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:25060; }","duration":"170.359376ms","start":"2026-07-17T18:55:54.88947Z","end":"2026-07-17T18:55:55.05983Z","steps":["trace[1203137543] 'agreement among raft nodes before linearized reading' (duration: 170.297214ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:55.059822Z","caller":"traceutil/trace.go:171","msg":"trace[858816937] transaction","detail":"{read_only:false; response_revision:25060; number_of_response:1; }","duration":"232.054413ms","start":"2026-07-17T18:55:54.82773Z","end":"2026-07-17T18:55:55.059784Z","steps":["trace[858816937] 'process raft request' (duration: 231.91834ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:58.511502Z","caller":"traceutil/trace.go:171","msg":"trace[1566588504] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"100.276536ms","start":"2026-07-17T18:55:58.411194Z","end":"2026-07-17T18:55:58.51147Z","steps":["trace[1566588504] 'process raft request' (duration: 100.235035ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:58.511934Z","caller":"traceutil/trace.go:171","msg":"trace[1055281715] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"101.280144ms","start":"2026-07-17T18:55:58.410641Z","end":"2026-07-17T18:55:58.511921Z","steps":["trace[1055281715] 'process raft request' (duration: 100.562784ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:58.512092Z","caller":"traceutil/trace.go:171","msg":"trace[463631425] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"101.242302ms","start":"2026-07-17T18:55:58.41084Z","end":"2026-07-17T18:55:58.512082Z","steps":["trace[463631425] 'process raft request' (duration: 100.509762ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:55:58.512223Z","caller":"traceutil/trace.go:171","msg":"trace[394417680] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"101.340355ms","start":"2026-07-17T18:55:58.410874Z","end":"2026-07-17T18:55:58.512214Z","steps":["trace[394417680] 'process raft request' (duration: 100.518932ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:02.67471Z","caller":"traceutil/trace.go:171","msg":"trace[1217280527] transaction","detail":"{read_only:false; response_revision:25129; number_of_response:1; }","duration":"149.936541ms","start":"2026-07-17T18:56:02.52475Z","end":"2026-07-17T18:56:02.674686Z","steps":["trace[1217280527] 'process raft request' (duration: 76.988181ms)","trace[1217280527] 'compare' (duration: 72.733624ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:56:02.68791Z","caller":"traceutil/trace.go:171","msg":"trace[757536973] linearizableReadLoop","detail":"{readStateIndex:25950; appliedIndex:25946; }","duration":"161.687036ms","start":"2026-07-17T18:56:02.526203Z","end":"2026-07-17T18:56:02.68789Z","steps":["trace[757536973] 'read index received' (duration: 75.520191ms)","trace[757536973] 'applied index is now lower than readState.Index' (duration: 86.165795ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:56:02.688082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.85924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-07-17T18:56:02.688108Z","caller":"traceutil/trace.go:171","msg":"trace[1933787874] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25132; }","duration":"161.947453ms","start":"2026-07-17T18:56:02.526152Z","end":"2026-07-17T18:56:02.6881Z","steps":["trace[1933787874] 'agreement among raft nodes before linearized reading' (duration: 161.809129ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:02.688319Z","caller":"traceutil/trace.go:171","msg":"trace[1803697132] transaction","detail":"{read_only:false; response_revision:25130; number_of_response:1; }","duration":"162.521069ms","start":"2026-07-17T18:56:02.525791Z","end":"2026-07-17T18:56:02.688313Z","steps":["trace[1803697132] 'process raft request' (duration: 161.911792ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:02.688446Z","caller":"traceutil/trace.go:171","msg":"trace[157603938] transaction","detail":"{read_only:false; response_revision:25132; number_of_response:1; }","duration":"162.468917ms","start":"2026-07-17T18:56:02.52594Z","end":"2026-07-17T18:56:02.688409Z","steps":["trace[157603938] 'process raft request' (duration: 161.916922ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:02.688573Z","caller":"traceutil/trace.go:171","msg":"trace[2099204423] transaction","detail":"{read_only:false; response_revision:25131; number_of_response:1; }","duration":"162.689934ms","start":"2026-07-17T18:56:02.525877Z","end":"2026-07-17T18:56:02.688567Z","steps":["trace[2099204423] 'process raft request' (duration: 161.950103ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:04.804369Z","caller":"traceutil/trace.go:171","msg":"trace[57956604] transaction","detail":"{read_only:false; response_revision:25146; number_of_response:1; }","duration":"110.401797ms","start":"2026-07-17T18:56:04.693932Z","end":"2026-07-17T18:56:04.804334Z","steps":["trace[57956604] 'process raft request' (duration: 110.099238ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:04.804703Z","caller":"traceutil/trace.go:171","msg":"trace[1090530920] transaction","detail":"{read_only:false; response_revision:25147; number_of_response:1; }","duration":"106.015195ms","start":"2026-07-17T18:56:04.69862Z","end":"2026-07-17T18:56:04.804635Z","steps":["trace[1090530920] 'process raft request' (duration: 105.553362ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:05.035584Z","caller":"traceutil/trace.go:171","msg":"trace[1638972284] transaction","detail":"{read_only:false; response_revision:25148; number_of_response:1; }","duration":"224.758272ms","start":"2026-07-17T18:56:04.810793Z","end":"2026-07-17T18:56:05.035552Z","steps":["trace[1638972284] 'process raft request' (duration: 126.005699ms)","trace[1638972284] 'compare' (duration: 98.564608ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:56:05.041346Z","caller":"traceutil/trace.go:171","msg":"trace[534555115] linearizableReadLoop","detail":"{readStateIndex:25967; appliedIndex:25965; }","duration":"122.649766ms","start":"2026-07-17T18:56:04.918666Z","end":"2026-07-17T18:56:05.041316Z","steps":["trace[534555115] 'read index received' (duration: 18.150733ms)","trace[534555115] 'applied index is now lower than readState.Index' (duration: 104.498153ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:56:05.041518Z","caller":"traceutil/trace.go:171","msg":"trace[1501973324] transaction","detail":"{read_only:false; response_revision:25149; number_of_response:1; }","duration":"230.45858ms","start":"2026-07-17T18:56:04.811017Z","end":"2026-07-17T18:56:05.041476Z","steps":["trace[1501973324] 'process raft request' (duration: 230.165192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:56:05.041559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.876172ms","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-07-17T18:56:05.041605Z","caller":"traceutil/trace.go:171","msg":"trace[402379735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25149; }","duration":"122.981084ms","start":"2026-07-17T18:56:04.918613Z","end":"2026-07-17T18:56:05.041594Z","steps":["trace[402379735] 'agreement among raft nodes before linearized reading' (duration: 122.8161ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:15.659791Z","caller":"traceutil/trace.go:171","msg":"trace[346385091] transaction","detail":"{read_only:false; response_revision:25225; number_of_response:1; }","duration":"126.179133ms","start":"2026-07-17T18:56:15.533581Z","end":"2026-07-17T18:56:15.65976Z","steps":["trace[346385091] 'process raft request' (duration: 126.006568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:56:15.929414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.00705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T18:56:15.929529Z","caller":"traceutil/trace.go:171","msg":"trace[1709169275] linearizableReadLoop","detail":"{readStateIndex:26046; appliedIndex:26045; }","duration":"241.173486ms","start":"2026-07-17T18:56:15.688336Z","end":"2026-07-17T18:56:15.929509Z","steps":["trace[1709169275] 'read index received' (duration: 24.850848ms)","trace[1709169275] 'applied index is now lower than readState.Index' (duration: 216.321018ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:56:15.92962Z","caller":"traceutil/trace.go:171","msg":"trace[1294922667] transaction","detail":"{read_only:false; response_revision:25226; number_of_response:1; }","duration":"255.961856ms","start":"2026-07-17T18:56:15.673623Z","end":"2026-07-17T18:56:15.929585Z","steps":["trace[1294922667] 'process raft request' (duration: 39.675518ms)","trace[1294922667] 'compare' (duration: 215.686021ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:56:15.929744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.378622ms","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-07-17T18:56:15.929799Z","caller":"traceutil/trace.go:171","msg":"trace[1416891804] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25226; }","duration":"241.477534ms","start":"2026-07-17T18:56:15.688304Z","end":"2026-07-17T18:56:15.929782Z","steps":["trace[1416891804] 'agreement among raft nodes before linearized reading' (duration: 241.285129ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:20.717116Z","caller":"traceutil/trace.go:171","msg":"trace[1018048483] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"117.428891ms","start":"2026-07-17T18:56:20.599639Z","end":"2026-07-17T18:56:20.717068Z","steps":["trace[1018048483] 'process raft request' (duration: 117.007249ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:21.116702Z","caller":"traceutil/trace.go:171","msg":"trace[1878628899] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"283.328964ms","start":"2026-07-17T18:56:20.833337Z","end":"2026-07-17T18:56:21.116665Z","steps":["trace[1878628899] 'process raft request' (duration: 228.714022ms)","trace[1878628899] 'compare' (duration: 54.124648ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:56:21.116746Z","caller":"traceutil/trace.go:171","msg":"trace[1703433396] transaction","detail":"{read_only:false; response_revision:25261; number_of_response:1; }","duration":"252.741766ms","start":"2026-07-17T18:56:20.863979Z","end":"2026-07-17T18:56:21.116721Z","steps":["trace[1703433396] 'process raft request' (duration: 252.610283ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:21.117158Z","caller":"traceutil/trace.go:171","msg":"trace[817178228] transaction","detail":"{read_only:false; response_revision:25262; number_of_response:1; }","duration":"247.916253ms","start":"2026-07-17T18:56:20.869196Z","end":"2026-07-17T18:56:21.117112Z","steps":["trace[817178228] 'process raft request' (duration: 247.483951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:56:21.117351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.952211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:56:21.117444Z","caller":"traceutil/trace.go:171","msg":"trace[1900568686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25262; }","duration":"107.140536ms","start":"2026-07-17T18:56:21.010267Z","end":"2026-07-17T18:56:21.117407Z","steps":["trace[1900568686] 'agreement among raft nodes before linearized reading' (duration: 106.937911ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:21.116962Z","caller":"traceutil/trace.go:171","msg":"trace[475037971] linearizableReadLoop","detail":"{readStateIndex:26083; appliedIndex:26080; }","duration":"106.624662ms","start":"2026-07-17T18:56:21.010301Z","end":"2026-07-17T18:56:21.116926Z","steps":["trace[475037971] 'read index received' (duration: 51.766363ms)","trace[475037971] 'applied index is now lower than readState.Index' (duration: 54.856459ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:56:26.883149Z","caller":"traceutil/trace.go:171","msg":"trace[997472939] transaction","detail":"{read_only:false; response_revision:25304; number_of_response:1; }","duration":"129.284369ms","start":"2026-07-17T18:56:26.753834Z","end":"2026-07-17T18:56:26.883118Z","steps":["trace[997472939] 'process raft request' (duration: 129.109064ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:27.020659Z","caller":"traceutil/trace.go:171","msg":"trace[285365647] transaction","detail":"{read_only:false; response_revision:25305; number_of_response:1; }","duration":"199.43603ms","start":"2026-07-17T18:56:26.821193Z","end":"2026-07-17T18:56:27.020629Z","steps":["trace[285365647] 'process raft request' (duration: 199.07427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:56:27.291498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.156404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T18:56:27.291818Z","caller":"traceutil/trace.go:171","msg":"trace[1105207083] linearizableReadLoop","detail":"{readStateIndex:26131; appliedIndex:26128; }","duration":"133.703411ms","start":"2026-07-17T18:56:27.158098Z","end":"2026-07-17T18:56:27.291801Z","steps":["trace[1105207083] 'read index received' (duration: 25.145776ms)","trace[1105207083] 'applied index is now lower than readState.Index' (duration: 108.556375ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:56:27.2919Z","caller":"traceutil/trace.go:171","msg":"trace[2125668268] transaction","detail":"{read_only:false; response_revision:25307; number_of_response:1; }","duration":"155.923656ms","start":"2026-07-17T18:56:27.13596Z","end":"2026-07-17T18:56:27.291884Z","steps":["trace[2125668268] 'process raft request' (duration: 155.69293ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:27.291961Z","caller":"traceutil/trace.go:171","msg":"trace[1281494515] transaction","detail":"{read_only:false; response_revision:25308; number_of_response:1; }","duration":"154.522227ms","start":"2026-07-17T18:56:27.137424Z","end":"2026-07-17T18:56:27.291946Z","steps":["trace[1281494515] 'process raft request' (duration: 154.312981ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:27.291731Z","caller":"traceutil/trace.go:171","msg":"trace[787330389] transaction","detail":"{read_only:false; response_revision:25306; number_of_response:1; }","duration":"158.657972ms","start":"2026-07-17T18:56:27.133041Z","end":"2026-07-17T18:56:27.291699Z","steps":["trace[787330389] 'process raft request' (duration: 50.18492ms)","trace[787330389] 'compare' (duration: 107.967918ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:56:27.292202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.090751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-17T18:56:27.292344Z","caller":"traceutil/trace.go:171","msg":"trace[42739506] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25308; }","duration":"134.242296ms","start":"2026-07-17T18:56:27.158052Z","end":"2026-07-17T18:56:27.292294Z","steps":["trace[42739506] 'agreement among raft nodes before linearized reading' (duration: 133.881156ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:27.563576Z","caller":"traceutil/trace.go:171","msg":"trace[1209788170] transaction","detail":"{read_only:false; response_revision:25309; number_of_response:1; }","duration":"264.816931ms","start":"2026-07-17T18:56:27.298734Z","end":"2026-07-17T18:56:27.563551Z","steps":["trace[1209788170] 'process raft request' (duration: 264.656846ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:27.607824Z","caller":"traceutil/trace.go:171","msg":"trace[582764838] transaction","detail":"{read_only:false; response_revision:25310; number_of_response:1; }","duration":"308.929882ms","start":"2026-07-17T18:56:27.298872Z","end":"2026-07-17T18:56:27.607802Z","steps":["trace[582764838] 'process raft request' (duration: 308.466399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:56:27.607951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:56:27.298849Z","time spent":"309.035084ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:56:27.608051Z","caller":"traceutil/trace.go:171","msg":"trace[427790969] transaction","detail":"{read_only:false; response_revision:25311; number_of_response:1; }","duration":"309.115717ms","start":"2026-07-17T18:56:27.298906Z","end":"2026-07-17T18:56:27.608021Z","steps":["trace[427790969] 'process raft request' (duration: 308.835699ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:27.608123Z","caller":"traceutil/trace.go:171","msg":"trace[1556070727] transaction","detail":"{read_only:false; response_revision:25312; number_of_response:1; }","duration":"309.068156ms","start":"2026-07-17T18:56:27.299048Z","end":"2026-07-17T18:56:27.608116Z","steps":["trace[1556070727] 'process raft request' (duration: 308.846139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:56:27.608221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:56:27.299019Z","time spent":"309.131888ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:56:27.60823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:56:27.2989Z","time spent":"309.22858ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:56:27.608071Z","caller":"traceutil/trace.go:171","msg":"trace[1905952468] transaction","detail":"{read_only:false; response_revision:25313; number_of_response:1; }","duration":"308.082878ms","start":"2026-07-17T18:56:27.299949Z","end":"2026-07-17T18:56:27.608032Z","steps":["trace[1905952468] 'process raft request' (duration: 308.041087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:56:27.608399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:56:27.299935Z","time spent":"308.427957ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:56:28.106171Z","caller":"traceutil/trace.go:171","msg":"trace[28065746] transaction","detail":"{read_only:false; response_revision:25315; number_of_response:1; }","duration":"140.865229ms","start":"2026-07-17T18:56:27.965263Z","end":"2026-07-17T18:56:28.106128Z","steps":["trace[28065746] 'process raft request' (duration: 140.648353ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:29.075712Z","caller":"traceutil/trace.go:171","msg":"trace[748354609] transaction","detail":"{read_only:false; response_revision:25319; number_of_response:1; }","duration":"185.396292ms","start":"2026-07-17T18:56:28.890258Z","end":"2026-07-17T18:56:29.075654Z","steps":["trace[748354609] 'process raft request' (duration: 128.441095ms)","trace[748354609] 'compare' (duration: 56.821874ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:56:43.680213Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066f9.wal"} {"level":"info","ts":"2026-07-17T18:56:47.417628Z","caller":"traceutil/trace.go:171","msg":"trace[347153563] transaction","detail":"{read_only:false; response_revision:25606; number_of_response:1; }","duration":"247.316725ms","start":"2026-07-17T18:56:47.170263Z","end":"2026-07-17T18:56:47.417579Z","steps":["trace[347153563] 'process raft request' (duration: 152.868282ms)","trace[347153563] 'compare' (duration: 93.858057ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:56:47.417605Z","caller":"traceutil/trace.go:171","msg":"trace[540937266] linearizableReadLoop","detail":"{readStateIndex:26435; appliedIndex:26433; }","duration":"226.170839ms","start":"2026-07-17T18:56:47.191386Z","end":"2026-07-17T18:56:47.417556Z","steps":["trace[540937266] 'read index received' (duration: 25.790773ms)","trace[540937266] 'applied index is now lower than readState.Index' (duration: 200.377906ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:56:47.417814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.411567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T18:56:47.417897Z","caller":"traceutil/trace.go:171","msg":"trace[743743823] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:25606; }","duration":"226.56015ms","start":"2026-07-17T18:56:47.191321Z","end":"2026-07-17T18:56:47.417881Z","steps":["trace[743743823] 'agreement among raft nodes before linearized reading' (duration: 226.313914ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:47.68752Z","caller":"traceutil/trace.go:171","msg":"trace[1278841504] transaction","detail":"{read_only:false; response_revision:25607; number_of_response:1; }","duration":"328.917005ms","start":"2026-07-17T18:56:47.358567Z","end":"2026-07-17T18:56:47.687484Z","steps":["trace[1278841504] 'process raft request' (duration: 277.790269ms)","trace[1278841504] 'compare' (duration: 50.954911ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:56:47.687707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:56:47.358535Z","time spent":"329.075109ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:56:47.689676Z","caller":"traceutil/trace.go:171","msg":"trace[1305578162] transaction","detail":"{read_only:false; response_revision:25608; number_of_response:1; }","duration":"311.577035ms","start":"2026-07-17T18:56:47.378081Z","end":"2026-07-17T18:56:47.689658Z","steps":["trace[1305578162] 'process raft request' (duration: 311.423121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:56:47.689852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:56:47.378057Z","time spent":"311.732849ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:56:47.689939Z","caller":"traceutil/trace.go:171","msg":"trace[752174561] transaction","detail":"{read_only:false; response_revision:25609; number_of_response:1; }","duration":"311.725939ms","start":"2026-07-17T18:56:47.378174Z","end":"2026-07-17T18:56:47.6899Z","steps":["trace[752174561] 'process raft request' (duration: 311.433881ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:47.689873Z","caller":"traceutil/trace.go:171","msg":"trace[1642117659] linearizableReadLoop","detail":"{readStateIndex:26438; appliedIndex:26435; }","duration":"272.116643ms","start":"2026-07-17T18:56:47.41772Z","end":"2026-07-17T18:56:47.689836Z","steps":["trace[1642117659] 'read index received' (duration: 218.783376ms)","trace[1642117659] 'applied index is now lower than readState.Index' (duration: 53.330667ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:56:47.690091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.991984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T18:56:47.69024Z","caller":"traceutil/trace.go:171","msg":"trace[1727961693] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25609; }","duration":"451.199589ms","start":"2026-07-17T18:56:47.239025Z","end":"2026-07-17T18:56:47.690225Z","steps":["trace[1727961693] 'agreement among raft nodes before linearized reading' (duration: 450.902671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:56:47.690093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:56:47.378167Z","time spent":"311.839822ms","remote":"127.0.0.1:46434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:56:47.690293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:56:47.239007Z","time spent":"451.271461ms","remote":"127.0.0.1:46450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":95,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-07-17T18:56:47.690576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.865531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T18:56:47.690653Z","caller":"traceutil/trace.go:171","msg":"trace[1671887485] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:25609; }","duration":"235.024856ms","start":"2026-07-17T18:56:47.455607Z","end":"2026-07-17T18:56:47.690632Z","steps":["trace[1671887485] 'agreement among raft nodes before linearized reading' (duration: 234.693897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:56:47.97564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.041042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T18:56:47.975807Z","caller":"traceutil/trace.go:171","msg":"trace[1048033264] transaction","detail":"{read_only:false; response_revision:25611; number_of_response:1; }","duration":"210.662371ms","start":"2026-07-17T18:56:47.765123Z","end":"2026-07-17T18:56:47.975785Z","steps":["trace[1048033264] 'process raft request' (duration: 42.389913ms)","trace[1048033264] 'compare' (duration: 167.848387ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:56:48.005412Z","caller":"traceutil/trace.go:171","msg":"trace[1694109343] transaction","detail":"{read_only:false; response_revision:25612; number_of_response:1; }","duration":"239.723837ms","start":"2026-07-17T18:56:47.765655Z","end":"2026-07-17T18:56:48.005379Z","steps":["trace[1694109343] 'process raft request' (duration: 239.439059ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:48.005524Z","caller":"traceutil/trace.go:171","msg":"trace[1721468212] transaction","detail":"{read_only:false; response_revision:25613; number_of_response:1; }","duration":"239.434209ms","start":"2026-07-17T18:56:47.766051Z","end":"2026-07-17T18:56:48.005485Z","steps":["trace[1721468212] 'process raft request' (duration: 239.204202ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:48.00564Z","caller":"traceutil/trace.go:171","msg":"trace[1122627529] transaction","detail":"{read_only:false; response_revision:25614; number_of_response:1; }","duration":"239.15001ms","start":"2026-07-17T18:56:47.766462Z","end":"2026-07-17T18:56:48.005612Z","steps":["trace[1122627529] 'process raft request' (duration: 238.831271ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:56:48.010741Z","caller":"traceutil/trace.go:171","msg":"trace[333280897] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"180.674842ms","start":"2026-07-17T18:56:47.830017Z","end":"2026-07-17T18:56:48.010691Z","steps":["trace[333280897] 'process raft request' (duration: 180.559259ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:57:58.306389Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23356} {"level":"info","ts":"2026-07-17T18:57:58.435093Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23356,"took":"125.152554ms","hash":1571415038,"current-db-size-bytes":59363328,"current-db-size":"59 MB","current-db-size-in-use-bytes":36864000,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-17T18:57:58.435148Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1571415038,"revision":23356,"compact-revision":20134} {"level":"info","ts":"2026-07-17T19:02:58.312662Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26233} {"level":"info","ts":"2026-07-17T19:02:58.457685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26233,"took":"139.001988ms","hash":76499230,"current-db-size-bytes":59363328,"current-db-size":"59 MB","current-db-size-in-use-bytes":34717696,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-17T19:02:58.457854Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":76499230,"revision":26233,"compact-revision":23356} {"level":"info","ts":"2026-07-17T19:03:21.342941Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"71d28e9ac1bc4e5d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-17T19:03:21.347903Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-17T19:03:21.348015Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-17T19:07:58.319034Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28913} {"level":"info","ts":"2026-07-17T19:07:58.449077Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28913,"took":"125.777385ms","hash":908740264,"current-db-size-bytes":59363328,"current-db-size":"59 MB","current-db-size-in-use-bytes":30801920,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-17T19:07:58.449124Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":908740264,"revision":28913,"compact-revision":26233} {"level":"info","ts":"2026-07-17T19:12:58.326357Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31166} {"level":"info","ts":"2026-07-17T19:12:58.453626Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31166,"took":"123.083632ms","hash":2268855665,"current-db-size-bytes":59363328,"current-db-size":"59 MB","current-db-size-in-use-bytes":30732288,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-17T19:12:58.453695Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2268855665,"revision":31166,"compact-revision":28913} {"level":"info","ts":"2026-07-17T19:17:58.332137Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33428} {"level":"info","ts":"2026-07-17T19:17:58.494253Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33428,"took":"147.53509ms","hash":671906732,"current-db-size-bytes":60407808,"current-db-size":"60 MB","current-db-size-in-use-bytes":32526336,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-17T19:17:58.494318Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":671906732,"revision":33428,"compact-revision":31166} {"level":"info","ts":"2026-07-17T19:22:58.339932Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35723} {"level":"info","ts":"2026-07-17T19:22:58.488493Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35723,"took":"144.180904ms","hash":3312462649,"current-db-size-bytes":62156800,"current-db-size":"62 MB","current-db-size-in-use-bytes":32329728,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-17T19:22:58.48857Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3312462649,"revision":35723,"compact-revision":33428} {"level":"info","ts":"2026-07-17T19:24:47.786397Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"71d28e9ac1bc4e5d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-17T19:24:47.792478Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-17T19:24:47.792738Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-17T19:27:58.346944Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37953} {"level":"info","ts":"2026-07-17T19:27:58.46452Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37953,"took":"112.677116ms","hash":1567865373,"current-db-size-bytes":62156800,"current-db-size":"62 MB","current-db-size-in-use-bytes":31821824,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-17T19:27:58.464594Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1567865373,"revision":37953,"compact-revision":35723}