{"level":"warn","ts":"2026-10-14T15:45:11.385569Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T15:45:11.385691Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.115:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.115:2380","--initial-cluster=instance=https://162.253.55.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.115:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-14T15:45:11.385779Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T15:45:11.38579Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.115:2380"]} {"level":"info","ts":"2026-10-14T15:45:11.385832Z","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-10-14T15:45:11.386755Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.115:2379"]} {"level":"info","ts":"2026-10-14T15:45:11.38688Z","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://162.253.55.115:2380"],"listen-peer-urls":["https://162.253.55.115:2380"],"advertise-client-urls":["https://162.253.55.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.115:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-14T15:45:11.390349Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.18062ms"} {"level":"info","ts":"2026-10-14T15:45:11.395838Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3fd97315619643d2","cluster-id":"5ce44a81422ca797"} {"level":"info","ts":"2026-10-14T15:45:11.395957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T15:45:11.396005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 became follower at term 0"} {"level":"info","ts":"2026-10-14T15:45:11.396027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3fd97315619643d2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T15:45:11.396039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 became follower at term 1"} {"level":"info","ts":"2026-10-14T15:45:11.396111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 switched to configuration voters=(4600835030004417490)"} {"level":"warn","ts":"2026-10-14T15:45:11.408093Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T15:45:11.410135Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T15:45:11.411633Z","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-10-14T15:45:11.414797Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3fd97315619643d2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T15:45:11.414999Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3fd97315619643d2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T15:45:11.41531Z","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-10-14T15:45:11.415462Z","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-10-14T15:45:11.415495Z","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-10-14T15:45:11.416977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 switched to configuration voters=(4600835030004417490)"} {"level":"info","ts":"2026-10-14T15:45:11.417128Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5ce44a81422ca797","local-member-id":"3fd97315619643d2","added-peer-id":"3fd97315619643d2","added-peer-peer-urls":["https://162.253.55.115:2380"]} {"level":"info","ts":"2026-10-14T15:45:11.420525Z","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-10-14T15:45:11.420681Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.115:2380"} {"level":"info","ts":"2026-10-14T15:45:11.420751Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.115:2380"} {"level":"info","ts":"2026-10-14T15:45:11.420956Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3fd97315619643d2","initial-advertise-peer-urls":["https://162.253.55.115:2380"],"listen-peer-urls":["https://162.253.55.115:2380"],"advertise-client-urls":["https://162.253.55.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T15:45:11.421021Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T15:45:12.196527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T15:45:12.196589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T15:45:12.196618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 received MsgPreVoteResp from 3fd97315619643d2 at term 1"} {"level":"info","ts":"2026-10-14T15:45:12.196632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 became candidate at term 2"} {"level":"info","ts":"2026-10-14T15:45:12.196637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 received MsgVoteResp from 3fd97315619643d2 at term 2"} {"level":"info","ts":"2026-10-14T15:45:12.196647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3fd97315619643d2 became leader at term 2"} {"level":"info","ts":"2026-10-14T15:45:12.196655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3fd97315619643d2 elected leader 3fd97315619643d2 at term 2"} {"level":"info","ts":"2026-10-14T15:45:12.197891Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:45:12.198937Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T15:45:12.198939Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3fd97315619643d2","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.115:2379]}","request-path":"/0/members/3fd97315619643d2/attributes","cluster-id":"5ce44a81422ca797","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T15:45:12.199016Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T15:45:12.199353Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T15:45:12.199376Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5ce44a81422ca797","local-member-id":"3fd97315619643d2","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:45:12.199409Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T15:45:12.199492Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:45:12.199536Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T15:45:12.202106Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T15:45:12.202446Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.115:2379"} {"level":"info","ts":"2026-10-14T15:45:38.101156Z","caller":"traceutil/trace.go:171","msg":"trace[1055374652] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"215.40745ms","start":"2026-10-14T15:45:37.885513Z","end":"2026-10-14T15:45:38.100921Z","steps":["trace[1055374652] 'process raft request' (duration: 215.124853ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:59.22228Z","caller":"traceutil/trace.go:171","msg":"trace[1375866342] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"250.91439ms","start":"2026-10-14T15:45:58.971327Z","end":"2026-10-14T15:45:59.222242Z","steps":["trace[1375866342] 'process raft request' (duration: 208.953545ms)","trace[1375866342] 'compare' (duration: 41.76579ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:45:59.232386Z","caller":"traceutil/trace.go:171","msg":"trace[1318765938] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"252.786471ms","start":"2026-10-14T15:45:58.979563Z","end":"2026-10-14T15:45:59.232349Z","steps":["trace[1318765938] 'process raft request' (duration: 252.569096ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:59.232957Z","caller":"traceutil/trace.go:171","msg":"trace[1689024413] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"219.250015ms","start":"2026-10-14T15:45:59.013663Z","end":"2026-10-14T15:45:59.232913Z","steps":["trace[1689024413] 'process raft request' (duration: 218.655291ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:45:59.233356Z","caller":"traceutil/trace.go:171","msg":"trace[824900808] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:622; }","duration":"219.313206ms","start":"2026-10-14T15:45:59.014016Z","end":"2026-10-14T15:45:59.233329Z","steps":["trace[824900808] 'read index received' (duration: 166.036639ms)","trace[824900808] 'applied index is now lower than readState.Index' (duration: 53.275257ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:45:59.233425Z","caller":"traceutil/trace.go:171","msg":"trace[12124950] transaction","detail":"{read_only:false; number_of_response:1; response_revision:601; }","duration":"214.967789ms","start":"2026-10-14T15:45:59.018409Z","end":"2026-10-14T15:45:59.233377Z","steps":["trace[12124950] 'process raft request' (duration: 214.307904ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:45:59.234655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.604854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:45:59.234978Z","caller":"traceutil/trace.go:171","msg":"trace[2012270360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"220.950502ms","start":"2026-10-14T15:45:59.013975Z","end":"2026-10-14T15:45:59.234925Z","steps":["trace[2012270360] 'agreement among raft nodes before linearized reading' (duration: 219.463289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:45:59.236116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.009622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-14T15:45:59.236345Z","caller":"traceutil/trace.go:171","msg":"trace[2100281002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"168.282719ms","start":"2026-10-14T15:45:59.068037Z","end":"2026-10-14T15:45:59.23632Z","steps":["trace[2100281002] 'agreement among raft nodes before linearized reading' (duration: 165.560498ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:03.370918Z","caller":"traceutil/trace.go:171","msg":"trace[17168740] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"100.605522ms","start":"2026-10-14T15:46:03.270274Z","end":"2026-10-14T15:46:03.370879Z","steps":["trace[17168740] 'process raft request' (duration: 100.54049ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:03.371036Z","caller":"traceutil/trace.go:171","msg":"trace[2030600316] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"101.479201ms","start":"2026-10-14T15:46:03.269513Z","end":"2026-10-14T15:46:03.370992Z","steps":["trace[2030600316] 'process raft request' (duration: 49.724778ms)","trace[2030600316] 'compare' (duration: 51.362364ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:46:06.472284Z","caller":"traceutil/trace.go:171","msg":"trace[1596746203] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"102.692078ms","start":"2026-10-14T15:46:06.369544Z","end":"2026-10-14T15:46:06.472237Z","steps":["trace[1596746203] 'process raft request' (duration: 102.484783ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:06.90618Z","caller":"traceutil/trace.go:171","msg":"trace[1196133336] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:692; }","duration":"265.613387ms","start":"2026-10-14T15:46:06.640525Z","end":"2026-10-14T15:46:06.906139Z","steps":["trace[1196133336] 'read index received' (duration: 210.98153ms)","trace[1196133336] 'applied index is now lower than readState.Index' (duration: 54.630547ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:46:06.906246Z","caller":"traceutil/trace.go:171","msg":"trace[1498164629] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"299.700536ms","start":"2026-10-14T15:46:06.606507Z","end":"2026-10-14T15:46:06.906208Z","steps":["trace[1498164629] 'process raft request' (duration: 245.061299ms)","trace[1498164629] 'compare' (duration: 54.351241ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:46:06.90654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.004556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-14T15:46:06.906605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.998665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9zl9j\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-14T15:46:06.906666Z","caller":"traceutil/trace.go:171","msg":"trace[406242397] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9zl9j; range_end:; response_count:1; response_revision:665; }","duration":"200.134928ms","start":"2026-10-14T15:46:06.706514Z","end":"2026-10-14T15:46:06.906649Z","steps":["trace[406242397] 'agreement among raft nodes before linearized reading' (duration: 199.841202ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:06.90676Z","caller":"traceutil/trace.go:171","msg":"trace[1572324373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:665; }","duration":"266.1788ms","start":"2026-10-14T15:46:06.640457Z","end":"2026-10-14T15:46:06.906636Z","steps":["trace[1572324373] 'agreement among raft nodes before linearized reading' (duration: 265.933404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:46:07.569596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.683714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:46:07.569774Z","caller":"traceutil/trace.go:171","msg":"trace[1269735421] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"654.48231ms","start":"2026-10-14T15:46:06.915269Z","end":"2026-10-14T15:46:07.569751Z","steps":["trace[1269735421] 'read index received' (duration: 420.579739ms)","trace[1269735421] 'applied index is now lower than readState.Index' (duration: 233.900351ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:46:07.569859Z","caller":"traceutil/trace.go:171","msg":"trace[605038980] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"656.982155ms","start":"2026-10-14T15:46:06.912814Z","end":"2026-10-14T15:46:07.569797Z","steps":["trace[605038980] 'process raft request' (duration: 422.997533ms)","trace[605038980] 'compare' (duration: 232.303985ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:46:07.57008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.770956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-10-14T15:46:07.570088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:46:06.912793Z","time spent":"657.16772ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:46:07.570211Z","caller":"traceutil/trace.go:171","msg":"trace[284908123] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:666; }","duration":"654.953ms","start":"2026-10-14T15:46:06.915208Z","end":"2026-10-14T15:46:07.570161Z","steps":["trace[284908123] 'agreement among raft nodes before linearized reading' (duration: 654.699315ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:46:07.570266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:46:06.915185Z","time spent":"655.070023ms","remote":"127.0.0.1:60514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-10-14T15:46:07.572122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.806949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-14T15:46:07.572121Z","caller":"traceutil/trace.go:171","msg":"trace[408258183] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"647.982205ms","start":"2026-10-14T15:46:06.924113Z","end":"2026-10-14T15:46:07.572095Z","steps":["trace[408258183] 'process raft request' (duration: 647.835612ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:07.572176Z","caller":"traceutil/trace.go:171","msg":"trace[444347662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:667; }","duration":"176.898301ms","start":"2026-10-14T15:46:07.395266Z","end":"2026-10-14T15:46:07.572164Z","steps":["trace[444347662] 'agreement among raft nodes before linearized reading' (duration: 176.797809ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:46:07.572235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.4435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-14T15:46:07.572261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:46:06.924066Z","time spent":"648.116008ms","remote":"127.0.0.1:60494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:46:07.572295Z","caller":"traceutil/trace.go:171","msg":"trace[706965743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:667; }","duration":"176.588514ms","start":"2026-10-14T15:46:07.395691Z","end":"2026-10-14T15:46:07.572279Z","steps":["trace[706965743] 'agreement among raft nodes before linearized reading' (duration: 176.41504ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:46:09.291003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.975663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:46:09.29125Z","caller":"traceutil/trace.go:171","msg":"trace[175618910] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"376.619141ms","start":"2026-10-14T15:46:08.914572Z","end":"2026-10-14T15:46:09.291191Z","steps":["trace[175618910] 'process raft request' (duration: 54.344231ms)","trace[175618910] 'compare' (duration: 321.761138ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:46:09.29132Z","caller":"traceutil/trace.go:171","msg":"trace[991388958] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"273.850641ms","start":"2026-10-14T15:46:09.017435Z","end":"2026-10-14T15:46:09.291286Z","steps":["trace[991388958] 'read index received' (duration: 74.382µs)","trace[991388958] 'applied index is now lower than readState.Index' (duration: 273.773559ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:46:09.291355Z","caller":"traceutil/trace.go:171","msg":"trace[2130649928] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"232.666513ms","start":"2026-10-14T15:46:09.058636Z","end":"2026-10-14T15:46:09.291302Z","steps":["trace[2130649928] 'process raft request' (duration: 232.555561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:46:09.291443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:46:08.914542Z","time spent":"376.801674ms","remote":"127.0.0.1:60368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T15:46:09.291468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.056505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:46:09.291522Z","caller":"traceutil/trace.go:171","msg":"trace[720841148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:684; }","duration":"274.174187ms","start":"2026-10-14T15:46:09.017327Z","end":"2026-10-14T15:46:09.291501Z","steps":["trace[720841148] 'agreement among raft nodes before linearized reading' (duration: 274.067225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:46:22.507311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.632441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:46:22.50743Z","caller":"traceutil/trace.go:171","msg":"trace[1230470867] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:827; }","duration":"139.798745ms","start":"2026-10-14T15:46:22.3676Z","end":"2026-10-14T15:46:22.507399Z","steps":["trace[1230470867] 'count revisions from in-memory index tree' (duration: 139.516118ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:39.146192Z","caller":"traceutil/trace.go:171","msg":"trace[1526023094] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"115.716758ms","start":"2026-10-14T15:46:39.030445Z","end":"2026-10-14T15:46:39.146162Z","steps":["trace[1526023094] 'process raft request' (duration: 110.44596ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:39.146212Z","caller":"traceutil/trace.go:171","msg":"trace[1607744048] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"111.930924ms","start":"2026-10-14T15:46:39.034253Z","end":"2026-10-14T15:46:39.146184Z","steps":["trace[1607744048] 'process raft request' (duration: 111.773451ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:39.266444Z","caller":"traceutil/trace.go:171","msg":"trace[1908139553] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"109.947169ms","start":"2026-10-14T15:46:39.156454Z","end":"2026-10-14T15:46:39.266401Z","steps":["trace[1908139553] 'process raft request' (duration: 100.751935ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:39.26654Z","caller":"traceutil/trace.go:171","msg":"trace[1171927585] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"108.912716ms","start":"2026-10-14T15:46:39.157604Z","end":"2026-10-14T15:46:39.266516Z","steps":["trace[1171927585] 'process raft request' (duration: 108.852815ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:39.266584Z","caller":"traceutil/trace.go:171","msg":"trace[1055755816] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"109.357647ms","start":"2026-10-14T15:46:39.157183Z","end":"2026-10-14T15:46:39.266541Z","steps":["trace[1055755816] 'process raft request' (duration: 109.177973ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:39.26671Z","caller":"traceutil/trace.go:171","msg":"trace[915713472] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"110.103123ms","start":"2026-10-14T15:46:39.156558Z","end":"2026-10-14T15:46:39.266661Z","steps":["trace[915713472] 'process raft request' (duration: 109.763466ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:39.266689Z","caller":"traceutil/trace.go:171","msg":"trace[1483312928] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"109.236214ms","start":"2026-10-14T15:46:39.157362Z","end":"2026-10-14T15:46:39.266598Z","steps":["trace[1483312928] 'process raft request' (duration: 109.06601ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:39.26687Z","caller":"traceutil/trace.go:171","msg":"trace[1482847144] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"109.258734ms","start":"2026-10-14T15:46:39.1576Z","end":"2026-10-14T15:46:39.266859Z","steps":["trace[1482847144] 'process raft request' (duration: 108.884435ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:39.266997Z","caller":"traceutil/trace.go:171","msg":"trace[1355946072] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"109.265134ms","start":"2026-10-14T15:46:39.157718Z","end":"2026-10-14T15:46:39.266983Z","steps":["trace[1355946072] 'process raft request' (duration: 109.150022ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:39.267041Z","caller":"traceutil/trace.go:171","msg":"trace[670715722] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"108.963787ms","start":"2026-10-14T15:46:39.158055Z","end":"2026-10-14T15:46:39.267019Z","steps":["trace[670715722] 'process raft request' (duration: 108.864175ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:39.267064Z","caller":"traceutil/trace.go:171","msg":"trace[2074495122] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"109.296605ms","start":"2026-10-14T15:46:39.157753Z","end":"2026-10-14T15:46:39.267049Z","steps":["trace[2074495122] 'process raft request' (duration: 109.137851ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:39.267092Z","caller":"traceutil/trace.go:171","msg":"trace[2056418990] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"109.425538ms","start":"2026-10-14T15:46:39.157658Z","end":"2026-10-14T15:46:39.267083Z","steps":["trace[2056418990] 'process raft request' (duration: 109.179832ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:39.267093Z","caller":"traceutil/trace.go:171","msg":"trace[1720261411] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"106.050862ms","start":"2026-10-14T15:46:39.161026Z","end":"2026-10-14T15:46:39.267077Z","steps":["trace[1720261411] 'process raft request' (duration: 105.977821ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:39.267033Z","caller":"traceutil/trace.go:171","msg":"trace[1322537458] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"109.347066ms","start":"2026-10-14T15:46:39.157602Z","end":"2026-10-14T15:46:39.266949Z","steps":["trace[1322537458] 'process raft request' (duration: 109.167992ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:39.267236Z","caller":"traceutil/trace.go:171","msg":"trace[399554734] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"105.253875ms","start":"2026-10-14T15:46:39.161969Z","end":"2026-10-14T15:46:39.267223Z","steps":["trace[399554734] 'process raft request' (duration: 105.071641ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:39.26727Z","caller":"traceutil/trace.go:171","msg":"trace[1333951695] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"107.400553ms","start":"2026-10-14T15:46:39.15978Z","end":"2026-10-14T15:46:39.26718Z","steps":["trace[1333951695] 'process raft request' (duration: 107.165788ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:46:39.307532Z","caller":"traceutil/trace.go:171","msg":"trace[800787185] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"112.765332ms","start":"2026-10-14T15:46:39.194743Z","end":"2026-10-14T15:46:39.307508Z","steps":["trace[800787185] 'process raft request' (duration: 112.611069ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:39.582708Z","caller":"traceutil/trace.go:171","msg":"trace[1402895426] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1964; }","duration":"160.948373ms","start":"2026-10-14T15:47:39.421732Z","end":"2026-10-14T15:47:39.582681Z","steps":["trace[1402895426] 'read index received' (duration: 160.774139ms)","trace[1402895426] 'applied index is now lower than readState.Index' (duration: 173.154µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:47:39.582778Z","caller":"traceutil/trace.go:171","msg":"trace[437407106] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"331.491364ms","start":"2026-10-14T15:47:39.251253Z","end":"2026-10-14T15:47:39.582745Z","steps":["trace[437407106] 'process raft request' (duration: 331.252098ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:47:39.582938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:47:39.251223Z","time spent":"331.630347ms","remote":"127.0.0.1:60494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:47:39.583045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.293781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-14T15:47:39.583135Z","caller":"traceutil/trace.go:171","msg":"trace[1294483276] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1890; }","duration":"161.434034ms","start":"2026-10-14T15:47:39.421679Z","end":"2026-10-14T15:47:39.583113Z","steps":["trace[1294483276] 'agreement among raft nodes before linearized reading' (duration: 161.165048ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:39.622095Z","caller":"traceutil/trace.go:171","msg":"trace[1285404523] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"175.209167ms","start":"2026-10-14T15:47:39.446808Z","end":"2026-10-14T15:47:39.622017Z","steps":["trace[1285404523] 'process raft request' (duration: 175.036553ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:39.741454Z","caller":"traceutil/trace.go:171","msg":"trace[1696676307] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"105.469044ms","start":"2026-10-14T15:47:39.635904Z","end":"2026-10-14T15:47:39.741373Z","steps":["trace[1696676307] 'process raft request' (duration: 55.228034ms)","trace[1696676307] 'compare' (duration: 49.888742ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:47:40.08271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.954447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:47:40.083014Z","caller":"traceutil/trace.go:171","msg":"trace[1017846913] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"333.948212ms","start":"2026-10-14T15:47:39.749014Z","end":"2026-10-14T15:47:40.082962Z","steps":["trace[1017846913] 'process raft request' (duration: 93.672907ms)","trace[1017846913] 'compare' (duration: 239.699542ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:47:40.0831Z","caller":"traceutil/trace.go:171","msg":"trace[80569714] linearizableReadLoop","detail":"{readStateIndex:1971; appliedIndex:1968; }","duration":"211.85863ms","start":"2026-10-14T15:47:39.87123Z","end":"2026-10-14T15:47:40.083089Z","steps":["trace[80569714] 'read index received' (duration: 38.151µs)","trace[80569714] 'applied index is now lower than readState.Index' (duration: 211.819039ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:47:40.083123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:47:39.748983Z","time spent":"334.087214ms","remote":"127.0.0.1:60590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:47:40.083312Z","caller":"traceutil/trace.go:171","msg":"trace[1425753203] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"333.429579ms","start":"2026-10-14T15:47:39.749873Z","end":"2026-10-14T15:47:40.083302Z","steps":["trace[1425753203] 'process raft request' (duration: 333.120812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:47:40.083405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:47:39.749856Z","time spent":"333.501872ms","remote":"127.0.0.1:60590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:47:40.08336Z","caller":"traceutil/trace.go:171","msg":"trace[1690725965] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"334.097675ms","start":"2026-10-14T15:47:39.749058Z","end":"2026-10-14T15:47:40.083155Z","steps":["trace[1690725965] 'process raft request' (duration: 333.817229ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:47:40.083642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.367941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bbdda7f7-a2cd-4058-8abb-ac50a4a6fb73\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-10-14T15:47:40.083697Z","caller":"traceutil/trace.go:171","msg":"trace[294610024] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bbdda7f7-a2cd-4058-8abb-ac50a4a6fb73; range_end:; response_count:1; response_revision:1896; }","duration":"212.511644ms","start":"2026-10-14T15:47:39.871169Z","end":"2026-10-14T15:47:40.083681Z","steps":["trace[294610024] 'agreement among raft nodes before linearized reading' (duration: 212.301569ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:47:40.083718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:47:39.749025Z","time spent":"334.622306ms","remote":"127.0.0.1:60470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:47:48.416516Z","caller":"traceutil/trace.go:171","msg":"trace[1101615397] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"104.415701ms","start":"2026-10-14T15:47:48.312074Z","end":"2026-10-14T15:47:48.41649Z","steps":["trace[1101615397] 'process raft request' (duration: 103.415689ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:48.420524Z","caller":"traceutil/trace.go:171","msg":"trace[1474341316] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"106.863406ms","start":"2026-10-14T15:47:48.313643Z","end":"2026-10-14T15:47:48.420507Z","steps":["trace[1474341316] 'process raft request' (duration: 106.418616ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:47:48.420859Z","caller":"traceutil/trace.go:171","msg":"trace[854039198] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"105.851903ms","start":"2026-10-14T15:47:48.314972Z","end":"2026-10-14T15:47:48.420824Z","steps":["trace[854039198] 'process raft request' (duration: 105.162808ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:48:24.645329Z","caller":"traceutil/trace.go:171","msg":"trace[1565887175] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"137.722826ms","start":"2026-10-14T15:48:24.507572Z","end":"2026-10-14T15:48:24.645294Z","steps":["trace[1565887175] 'process raft request' (duration: 137.470851ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:48:24.889517Z","caller":"traceutil/trace.go:171","msg":"trace[1872185636] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"180.794322ms","start":"2026-10-14T15:48:24.708654Z","end":"2026-10-14T15:48:24.889448Z","steps":["trace[1872185636] 'process raft request' (duration: 170.393148ms)","trace[1872185636] 'compare' (duration: 10.087517ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:48:24.88975Z","caller":"traceutil/trace.go:171","msg":"trace[181115919] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"143.893402ms","start":"2026-10-14T15:48:24.745813Z","end":"2026-10-14T15:48:24.889707Z","steps":["trace[181115919] 'process raft request' (duration: 143.543745ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:48:30.775264Z","caller":"traceutil/trace.go:171","msg":"trace[1324661761] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"100.320606ms","start":"2026-10-14T15:48:30.674916Z","end":"2026-10-14T15:48:30.775236Z","steps":["trace[1324661761] 'process raft request' (duration: 100.084541ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:49:31.499282Z","caller":"traceutil/trace.go:171","msg":"trace[39356801] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"176.931326ms","start":"2026-10-14T15:49:31.322326Z","end":"2026-10-14T15:49:31.499257Z","steps":["trace[39356801] 'process raft request' (duration: 109.480673ms)","trace[39356801] 'compare' (duration: 67.3119ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:52:37.290654Z","caller":"traceutil/trace.go:171","msg":"trace[428253690] transaction","detail":"{read_only:false; response_revision:3867; number_of_response:1; }","duration":"117.965794ms","start":"2026-10-14T15:52:37.172664Z","end":"2026-10-14T15:52:37.290629Z","steps":["trace[428253690] 'process raft request' (duration: 113.082492ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:52:37.290946Z","caller":"traceutil/trace.go:171","msg":"trace[2074035120] transaction","detail":"{read_only:false; response_revision:3868; number_of_response:1; }","duration":"117.963014ms","start":"2026-10-14T15:52:37.172973Z","end":"2026-10-14T15:52:37.290936Z","steps":["trace[2074035120] 'process raft request' (duration: 117.896642ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:53:06.050001Z","caller":"traceutil/trace.go:171","msg":"trace[1673694744] transaction","detail":"{read_only:false; response_revision:4077; number_of_response:1; }","duration":"177.558259ms","start":"2026-10-14T15:53:05.872397Z","end":"2026-10-14T15:53:06.049956Z","steps":["trace[1673694744] 'process raft request' (duration: 175.072899ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:28.906155Z","caller":"traceutil/trace.go:171","msg":"trace[607876679] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"103.195587ms","start":"2026-10-14T15:54:28.802939Z","end":"2026-10-14T15:54:28.906135Z","steps":["trace[607876679] 'process raft request' (duration: 44.363317ms)","trace[607876679] 'compare' (duration: 58.736938ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:54:28.917692Z","caller":"traceutil/trace.go:171","msg":"trace[631391619] linearizableReadLoop","detail":"{readStateIndex:5172; appliedIndex:5170; }","duration":"107.352136ms","start":"2026-10-14T15:54:28.81031Z","end":"2026-10-14T15:54:28.917662Z","steps":["trace[631391619] 'read index received' (duration: 37.006021ms)","trace[631391619] 'applied index is now lower than readState.Index' (duration: 70.344855ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:54:28.917756Z","caller":"traceutil/trace.go:171","msg":"trace[822527020] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"109.685211ms","start":"2026-10-14T15:54:28.808031Z","end":"2026-10-14T15:54:28.917717Z","steps":["trace[822527020] 'process raft request' (duration: 109.443325ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:28.917928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.598162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4737"} {"level":"info","ts":"2026-10-14T15:54:28.917992Z","caller":"traceutil/trace.go:171","msg":"trace[1718748951] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4982; }","duration":"107.700804ms","start":"2026-10-14T15:54:28.810277Z","end":"2026-10-14T15:54:28.917978Z","steps":["trace[1718748951] 'agreement among raft nodes before linearized reading' (duration: 107.50736ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:29.878642Z","caller":"traceutil/trace.go:171","msg":"trace[602770989] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"125.717463ms","start":"2026-10-14T15:54:29.752906Z","end":"2026-10-14T15:54:29.878623Z","steps":["trace[602770989] 'process raft request' (duration: 125.649732ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:29.878713Z","caller":"traceutil/trace.go:171","msg":"trace[1441604300] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"139.241085ms","start":"2026-10-14T15:54:29.739456Z","end":"2026-10-14T15:54:29.878697Z","steps":["trace[1441604300] 'process raft request' (duration: 104.635061ms)","trace[1441604300] 'compare' (duration: 34.341998ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:54:40.682941Z","caller":"traceutil/trace.go:171","msg":"trace[1661337919] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"369.277851ms","start":"2026-10-14T15:54:40.313642Z","end":"2026-10-14T15:54:40.682919Z","steps":["trace[1661337919] 'process raft request' (duration: 368.839091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:40.683145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:54:40.313615Z","time spent":"369.413705ms","remote":"127.0.0.1:49712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":414675,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:54:40.73967Z","caller":"traceutil/trace.go:171","msg":"trace[1402213868] linearizableReadLoop","detail":"{readStateIndex:5478; appliedIndex:5474; }","duration":"190.969396ms","start":"2026-10-14T15:54:40.548676Z","end":"2026-10-14T15:54:40.739645Z","steps":["trace[1402213868] 'read index received' (duration: 133.792425ms)","trace[1402213868] 'applied index is now lower than readState.Index' (duration: 57.175851ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:54:40.739691Z","caller":"traceutil/trace.go:171","msg":"trace[423328826] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"328.326796ms","start":"2026-10-14T15:54:40.411326Z","end":"2026-10-14T15:54:40.739653Z","steps":["trace[423328826] 'process raft request' (duration: 328.178392ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:40.739716Z","caller":"traceutil/trace.go:171","msg":"trace[168014004] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"242.909913ms","start":"2026-10-14T15:54:40.496773Z","end":"2026-10-14T15:54:40.739683Z","steps":["trace[168014004] 'process raft request' (duration: 242.829911ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:40.739745Z","caller":"traceutil/trace.go:171","msg":"trace[22971921] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"329.110624ms","start":"2026-10-14T15:54:40.410606Z","end":"2026-10-14T15:54:40.739717Z","steps":["trace[22971921] 'process raft request' (duration: 323.358598ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:40.739933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.240962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6c22f497-c345-48fc-be75-81d8acae6a69\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-14T15:54:40.739989Z","caller":"traceutil/trace.go:171","msg":"trace[1462890242] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6c22f497-c345-48fc-be75-81d8acae6a69; range_end:; response_count:1; response_revision:5281; }","duration":"191.366335ms","start":"2026-10-14T15:54:40.548612Z","end":"2026-10-14T15:54:40.739978Z","steps":["trace[1462890242] 'agreement among raft nodes before linearized reading' (duration: 191.16934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:40.740008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.324225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cf2zq\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-14T15:54:40.740053Z","caller":"traceutil/trace.go:171","msg":"trace[89315861] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cf2zq; range_end:; response_count:1; response_revision:5281; }","duration":"178.408496ms","start":"2026-10-14T15:54:40.561634Z","end":"2026-10-14T15:54:40.740042Z","steps":["trace[89315861] 'agreement among raft nodes before linearized reading' (duration: 178.241642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:40.739868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:54:40.410587Z","time spent":"329.216947ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:54:40.740477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:54:40.411318Z","time spent":"328.448739ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:54:42.853249Z","caller":"traceutil/trace.go:171","msg":"trace[709871862] linearizableReadLoop","detail":"{readStateIndex:5530; appliedIndex:5529; }","duration":"102.255634ms","start":"2026-10-14T15:54:42.75097Z","end":"2026-10-14T15:54:42.853226Z","steps":["trace[709871862] 'read index received' (duration: 4.352073ms)","trace[709871862] 'applied index is now lower than readState.Index' (duration: 97.902431ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:54:42.853419Z","caller":"traceutil/trace.go:171","msg":"trace[481015222] transaction","detail":"{read_only:false; response_revision:5330; number_of_response:1; }","duration":"106.292351ms","start":"2026-10-14T15:54:42.747054Z","end":"2026-10-14T15:54:42.853347Z","steps":["trace[481015222] 'process raft request' (duration: 106.019404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:42.853505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.513121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cf2zq\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-14T15:54:42.853569Z","caller":"traceutil/trace.go:171","msg":"trace[269669208] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cf2zq; range_end:; response_count:1; response_revision:5330; }","duration":"102.614303ms","start":"2026-10-14T15:54:42.750943Z","end":"2026-10-14T15:54:42.853558Z","steps":["trace[269669208] 'agreement among raft nodes before linearized reading' (duration: 102.377757ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:54.320346Z","caller":"traceutil/trace.go:171","msg":"trace[1395756972] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5519; }","duration":"118.02227ms","start":"2026-10-14T15:54:54.2023Z","end":"2026-10-14T15:54:54.320322Z","steps":["trace[1395756972] 'process raft request' (duration: 117.931078ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:54.515749Z","caller":"traceutil/trace.go:171","msg":"trace[1147701086] transaction","detail":"{read_only:false; response_revision:5533; number_of_response:1; }","duration":"108.316589ms","start":"2026-10-14T15:54:54.4074Z","end":"2026-10-14T15:54:54.515716Z","steps":["trace[1147701086] 'process raft request' (duration: 53.370991ms)","trace[1147701086] 'compare' (duration: 54.797164ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:54:54.516675Z","caller":"traceutil/trace.go:171","msg":"trace[1083800483] transaction","detail":"{read_only:false; response_revision:5534; number_of_response:1; }","duration":"106.936776ms","start":"2026-10-14T15:54:54.409716Z","end":"2026-10-14T15:54:54.516653Z","steps":["trace[1083800483] 'process raft request' (duration: 106.27531ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:56.373492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.69386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-5598f8449d-h5n6p\" ","response":"range_response_count:1 size:11848"} {"level":"warn","ts":"2026-10-14T15:54:56.373522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.910888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:54:56.373606Z","caller":"traceutil/trace.go:171","msg":"trace[266631979] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-5598f8449d-h5n6p; range_end:; response_count:1; response_revision:5565; }","duration":"282.851264ms","start":"2026-10-14T15:54:56.090734Z","end":"2026-10-14T15:54:56.373585Z","steps":["trace[266631979] 'range keys from in-memory index tree' (duration: 282.484956ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:54:56.373651Z","caller":"traceutil/trace.go:171","msg":"trace[2010863481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5565; }","duration":"357.108782ms","start":"2026-10-14T15:54:56.016512Z","end":"2026-10-14T15:54:56.373621Z","steps":["trace[2010863481] 'range keys from in-memory index tree' (duration: 356.772214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:54:56.373708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:54:56.016493Z","time spent":"357.200655ms","remote":"127.0.0.1:60266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-14T15:55:05.167594Z","caller":"traceutil/trace.go:171","msg":"trace[1152630390] transaction","detail":"{read_only:false; response_revision:5723; number_of_response:1; }","duration":"330.480269ms","start":"2026-10-14T15:55:04.837056Z","end":"2026-10-14T15:55:05.167536Z","steps":["trace[1152630390] 'process raft request' (duration: 330.303774ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.167921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:04.837028Z","time spent":"330.754014ms","remote":"127.0.0.1:60494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:55:05.23978Z","caller":"traceutil/trace.go:171","msg":"trace[707248521] linearizableReadLoop","detail":"{readStateIndex:5936; appliedIndex:5933; }","duration":"252.336257ms","start":"2026-10-14T15:55:04.987419Z","end":"2026-10-14T15:55:05.239756Z","steps":["trace[707248521] 'read index received' (duration: 179.947544ms)","trace[707248521] 'applied index is now lower than readState.Index' (duration: 72.387853ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:55:05.239899Z","caller":"traceutil/trace.go:171","msg":"trace[1216126261] transaction","detail":"{read_only:false; response_revision:5725; number_of_response:1; }","duration":"262.435168ms","start":"2026-10-14T15:55:04.97745Z","end":"2026-10-14T15:55:05.239885Z","steps":["trace[1216126261] 'process raft request' (duration: 262.259294ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:05.239894Z","caller":"traceutil/trace.go:171","msg":"trace[926342551] transaction","detail":"{read_only:false; response_revision:5724; number_of_response:1; }","duration":"381.009241ms","start":"2026-10-14T15:55:04.858813Z","end":"2026-10-14T15:55:05.239823Z","steps":["trace[926342551] 'process raft request' (duration: 380.760735ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.240113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:04.858792Z","time spent":"381.213496ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:55:05.240172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.841919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T15:55:05.240238Z","caller":"traceutil/trace.go:171","msg":"trace[1241717007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5725; }","duration":"223.950731ms","start":"2026-10-14T15:55:05.016273Z","end":"2026-10-14T15:55:05.240224Z","steps":["trace[1241717007] 'agreement among raft nodes before linearized reading' (duration: 223.796828ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.240395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.970343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cf2zq\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-14T15:55:05.240437Z","caller":"traceutil/trace.go:171","msg":"trace[1471507620] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cf2zq; range_end:; response_count:1; response_revision:5725; }","duration":"253.015234ms","start":"2026-10-14T15:55:04.987411Z","end":"2026-10-14T15:55:05.240426Z","steps":["trace[1471507620] 'agreement among raft nodes before linearized reading' (duration: 252.881981ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.240455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.64641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d0416ee0-3efc-4619-b28a-8fba02a7def3\" ","response":"range_response_count:1 size:3766"} {"level":"warn","ts":"2026-10-14T15:55:05.240533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.808059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-14T15:55:05.240637Z","caller":"traceutil/trace.go:171","msg":"trace[1707187606] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d0416ee0-3efc-4619-b28a-8fba02a7def3; range_end:; response_count:1; response_revision:5725; }","duration":"177.893765ms","start":"2026-10-14T15:55:05.062713Z","end":"2026-10-14T15:55:05.240607Z","steps":["trace[1707187606] 'agreement among raft nodes before linearized reading' (duration: 177.601968ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:05.240675Z","caller":"traceutil/trace.go:171","msg":"trace[1337684167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5725; }","duration":"106.001043ms","start":"2026-10-14T15:55:05.134647Z","end":"2026-10-14T15:55:05.240648Z","steps":["trace[1337684167] 'agreement among raft nodes before linearized reading' (duration: 105.629484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.694329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.158101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T15:55:05.694618Z","caller":"traceutil/trace.go:171","msg":"trace[314576895] linearizableReadLoop","detail":"{readStateIndex:5938; appliedIndex:5936; }","duration":"448.346204ms","start":"2026-10-14T15:55:05.246251Z","end":"2026-10-14T15:55:05.694597Z","steps":["trace[314576895] 'read index received' (duration: 67.791294ms)","trace[314576895] 'applied index is now lower than readState.Index' (duration: 380.55366ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:55:05.694731Z","caller":"traceutil/trace.go:171","msg":"trace[355172866] transaction","detail":"{read_only:false; response_revision:5726; number_of_response:1; }","duration":"449.498111ms","start":"2026-10-14T15:55:05.245213Z","end":"2026-10-14T15:55:05.694711Z","steps":["trace[355172866] 'process raft request' (duration: 68.849119ms)","trace[355172866] 'compare' (duration: 379.861224ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T15:55:05.694819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.245193Z","time spent":"449.580783ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T15:55:05.694825Z","caller":"traceutil/trace.go:171","msg":"trace[690249983] transaction","detail":"{read_only:false; response_revision:5727; number_of_response:1; }","duration":"449.469581ms","start":"2026-10-14T15:55:05.245302Z","end":"2026-10-14T15:55:05.694772Z","steps":["trace[690249983] 'process raft request' (duration: 449.170194ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.694921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.245276Z","time spent":"449.594333ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T15:55:05.695679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.398479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80109"} {"level":"info","ts":"2026-10-14T15:55:05.695767Z","caller":"traceutil/trace.go:171","msg":"trace[1104414646] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5727; }","duration":"449.530192ms","start":"2026-10-14T15:55:05.246218Z","end":"2026-10-14T15:55:05.695748Z","steps":["trace[1104414646] 'agreement among raft nodes before linearized reading' (duration: 448.963409ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.69581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.246207Z","time spent":"449.593683ms","remote":"127.0.0.1:60494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":9,"response size":80131,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-10-14T15:55:05.700242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.176512ms","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-10-14T15:55:05.700311Z","caller":"traceutil/trace.go:171","msg":"trace[887745167] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5728; }","duration":"417.288685ms","start":"2026-10-14T15:55:05.283007Z","end":"2026-10-14T15:55:05.700296Z","steps":["trace[887745167] 'agreement among raft nodes before linearized reading' (duration: 417.09295ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.700343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T15:55:05.282986Z","time spent":"417.349827ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-14T15:55:05.700623Z","caller":"traceutil/trace.go:171","msg":"trace[1368333446] transaction","detail":"{read_only:false; response_revision:5728; number_of_response:1; }","duration":"115.426428ms","start":"2026-10-14T15:55:05.585186Z","end":"2026-10-14T15:55:05.700612Z","steps":["trace[1368333446] 'process raft request' (duration: 114.786823ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.701106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.225598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-14T15:55:05.701168Z","caller":"traceutil/trace.go:171","msg":"trace[304453892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5728; }","duration":"271.32616ms","start":"2026-10-14T15:55:05.429826Z","end":"2026-10-14T15:55:05.701153Z","steps":["trace[304453892] 'agreement among raft nodes before linearized reading' (duration: 271.160046ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:05.701222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.918822ms","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-10-14T15:55:05.701295Z","caller":"traceutil/trace.go:171","msg":"trace[8904833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5728; }","duration":"211.038304ms","start":"2026-10-14T15:55:05.490236Z","end":"2026-10-14T15:55:05.701275Z","steps":["trace[8904833] 'agreement among raft nodes before linearized reading' (duration: 210.810329ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:55:12.235417Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2966} {"level":"info","ts":"2026-10-14T15:55:12.314576Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2966,"took":"77.453504ms","hash":3600181769,"current-db-size-bytes":55525376,"current-db-size":"56 MB","current-db-size-in-use-bytes":32247808,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T15:55:12.314642Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3600181769,"revision":2966,"compact-revision":-1} {"level":"info","ts":"2026-10-14T15:55:17.439734Z","caller":"traceutil/trace.go:171","msg":"trace[2043150719] linearizableReadLoop","detail":"{readStateIndex:6108; appliedIndex:6107; }","duration":"115.309836ms","start":"2026-10-14T15:55:17.324393Z","end":"2026-10-14T15:55:17.439703Z","steps":["trace[2043150719] 'read index received' (duration: 114.823584ms)","trace[2043150719] 'applied index is now lower than readState.Index' (duration: 484.752µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T15:55:17.43982Z","caller":"traceutil/trace.go:171","msg":"trace[71052754] transaction","detail":"{read_only:false; response_revision:5892; number_of_response:1; }","duration":"147.397139ms","start":"2026-10-14T15:55:17.292391Z","end":"2026-10-14T15:55:17.439788Z","steps":["trace[71052754] 'process raft request' (duration: 147.111442ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T15:55:17.440273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.827578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T15:55:17.440396Z","caller":"traceutil/trace.go:171","msg":"trace[2133171757] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:5892; }","duration":"116.021373ms","start":"2026-10-14T15:55:17.324349Z","end":"2026-10-14T15:55:17.44037Z","steps":["trace[2133171757] 'agreement among raft nodes before linearized reading' (duration: 115.50814ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:56:05.501933Z","caller":"traceutil/trace.go:171","msg":"trace[1787580783] transaction","detail":"{read_only:false; response_revision:6303; number_of_response:1; }","duration":"108.821067ms","start":"2026-10-14T15:56:05.393087Z","end":"2026-10-14T15:56:05.501908Z","steps":["trace[1787580783] 'process raft request' (duration: 108.627882ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:56:05.502187Z","caller":"traceutil/trace.go:171","msg":"trace[703929369] transaction","detail":"{read_only:false; response_revision:6304; number_of_response:1; }","duration":"108.897708ms","start":"2026-10-14T15:56:05.393256Z","end":"2026-10-14T15:56:05.502153Z","steps":["trace[703929369] 'process raft request' (duration: 108.597161ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:56:05.502403Z","caller":"traceutil/trace.go:171","msg":"trace[722896236] transaction","detail":"{read_only:false; response_revision:6305; number_of_response:1; }","duration":"109.084183ms","start":"2026-10-14T15:56:05.393292Z","end":"2026-10-14T15:56:05.502376Z","steps":["trace[722896236] 'process raft request' (duration: 108.770125ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:56:05.502735Z","caller":"traceutil/trace.go:171","msg":"trace[752687565] transaction","detail":"{read_only:false; response_revision:6306; number_of_response:1; }","duration":"109.351739ms","start":"2026-10-14T15:56:05.393367Z","end":"2026-10-14T15:56:05.502719Z","steps":["trace[752687565] 'process raft request' (duration: 108.933019ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:56:05.503051Z","caller":"traceutil/trace.go:171","msg":"trace[1026948511] transaction","detail":"{read_only:false; response_revision:6308; number_of_response:1; }","duration":"104.916854ms","start":"2026-10-14T15:56:05.398118Z","end":"2026-10-14T15:56:05.503035Z","steps":["trace[1026948511] 'process raft request' (duration: 104.819582ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T15:56:05.503138Z","caller":"traceutil/trace.go:171","msg":"trace[1128282881] transaction","detail":"{read_only:false; response_revision:6307; number_of_response:1; }","duration":"109.128174ms","start":"2026-10-14T15:56:05.393991Z","end":"2026-10-14T15:56:05.503119Z","steps":["trace[1128282881] 'process raft request' (duration: 108.657393ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:00:12.244461Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5845} {"level":"info","ts":"2026-10-14T16:00:12.334288Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5845,"took":"86.758926ms","hash":1839149251,"current-db-size-bytes":55525376,"current-db-size":"56 MB","current-db-size-in-use-bytes":26734592,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-14T16:00:12.334347Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1839149251,"revision":5845,"compact-revision":2966} {"level":"info","ts":"2026-10-14T16:01:07.294141Z","caller":"traceutil/trace.go:171","msg":"trace[105435937] transaction","detail":"{read_only:false; response_revision:8543; number_of_response:1; }","duration":"108.367649ms","start":"2026-10-14T16:01:07.18575Z","end":"2026-10-14T16:01:07.294118Z","steps":["trace[105435937] 'process raft request' (duration: 108.210754ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:18.005769Z","caller":"traceutil/trace.go:171","msg":"trace[1622552822] linearizableReadLoop","detail":"{readStateIndex:8904; appliedIndex:8903; }","duration":"300.015969ms","start":"2026-10-14T16:01:17.705728Z","end":"2026-10-14T16:01:18.005744Z","steps":["trace[1622552822] 'read index received' (duration: 299.744262ms)","trace[1622552822] 'applied index is now lower than readState.Index' (duration: 270.517µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:01:18.005824Z","caller":"traceutil/trace.go:171","msg":"trace[835081972] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"310.398148ms","start":"2026-10-14T16:01:17.695401Z","end":"2026-10-14T16:01:18.005799Z","steps":["trace[835081972] 'process raft request' (duration: 310.127931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:18.005983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:17.695385Z","time spent":"310.523671ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:01:18.006011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.126809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-14T16:01:18.006116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.710054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-10-14T16:01:18.006144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.342473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:01:18.006169Z","caller":"traceutil/trace.go:171","msg":"trace[406178475] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8599; }","duration":"166.785185ms","start":"2026-10-14T16:01:17.839368Z","end":"2026-10-14T16:01:18.006153Z","steps":["trace[406178475] 'agreement among raft nodes before linearized reading' (duration: 166.638392ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:18.006181Z","caller":"traceutil/trace.go:171","msg":"trace[705396866] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:8599; }","duration":"168.446146ms","start":"2026-10-14T16:01:17.837724Z","end":"2026-10-14T16:01:18.00617Z","steps":["trace[705396866] 'agreement among raft nodes before linearized reading' (duration: 168.355204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:18.006181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.44478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-14T16:01:18.006293Z","caller":"traceutil/trace.go:171","msg":"trace[1557890986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8599; }","duration":"300.606853ms","start":"2026-10-14T16:01:17.705674Z","end":"2026-10-14T16:01:18.006281Z","steps":["trace[1557890986] 'agreement among raft nodes before linearized reading' (duration: 300.199564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:18.006317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:17.705653Z","time spent":"300.659354ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-14T16:01:18.006497Z","caller":"traceutil/trace.go:171","msg":"trace[1168689920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8599; }","duration":"244.222232ms","start":"2026-10-14T16:01:17.761813Z","end":"2026-10-14T16:01:18.006035Z","steps":["trace[1168689920] 'agreement among raft nodes before linearized reading' (duration: 244.088268ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:18.115348Z","caller":"traceutil/trace.go:171","msg":"trace[1218857863] transaction","detail":"{read_only:false; response_revision:8600; number_of_response:1; }","duration":"104.059183ms","start":"2026-10-14T16:01:18.011256Z","end":"2026-10-14T16:01:18.115316Z","steps":["trace[1218857863] 'process raft request' (duration: 58.528423ms)","trace[1218857863] 'compare' (duration: 45.392247ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:01:18.13969Z","caller":"traceutil/trace.go:171","msg":"trace[352322857] transaction","detail":"{read_only:false; response_revision:8601; number_of_response:1; }","duration":"128.229304ms","start":"2026-10-14T16:01:18.011444Z","end":"2026-10-14T16:01:18.139673Z","steps":["trace[352322857] 'process raft request' (duration: 128.07259ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:18.139944Z","caller":"traceutil/trace.go:171","msg":"trace[1239529560] transaction","detail":"{read_only:false; response_revision:8602; number_of_response:1; }","duration":"128.110691ms","start":"2026-10-14T16:01:18.011818Z","end":"2026-10-14T16:01:18.139928Z","steps":["trace[1239529560] 'process raft request' (duration: 127.815384ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:18.140096Z","caller":"traceutil/trace.go:171","msg":"trace[1429008447] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"128.189772ms","start":"2026-10-14T16:01:18.011888Z","end":"2026-10-14T16:01:18.140078Z","steps":["trace[1429008447] 'process raft request' (duration: 127.978007ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:18.140421Z","caller":"traceutil/trace.go:171","msg":"trace[1304044266] linearizableReadLoop","detail":"{readStateIndex:8909; appliedIndex:8904; }","duration":"126.684336ms","start":"2026-10-14T16:01:18.01372Z","end":"2026-10-14T16:01:18.140404Z","steps":["trace[1304044266] 'read index received' (duration: 56.176716ms)","trace[1304044266] 'applied index is now lower than readState.Index' (duration: 70.50649ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:01:18.14048Z","caller":"traceutil/trace.go:171","msg":"trace[721908009] transaction","detail":"{read_only:false; response_revision:8604; number_of_response:1; }","duration":"128.363467ms","start":"2026-10-14T16:01:18.012103Z","end":"2026-10-14T16:01:18.140467Z","steps":["trace[721908009] 'process raft request' (duration: 127.916496ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:18.140834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.110947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223833"} {"level":"info","ts":"2026-10-14T16:01:18.140911Z","caller":"traceutil/trace.go:171","msg":"trace[542420001] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8604; }","duration":"127.209399ms","start":"2026-10-14T16:01:18.013688Z","end":"2026-10-14T16:01:18.140898Z","steps":["trace[542420001] 'agreement among raft nodes before linearized reading' (duration: 126.806759ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:18.919304Z","caller":"traceutil/trace.go:171","msg":"trace[1933765769] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"150.991519ms","start":"2026-10-14T16:01:18.768287Z","end":"2026-10-14T16:01:18.919278Z","steps":["trace[1933765769] 'process raft request' (duration: 112.427675ms)","trace[1933765769] 'compare' (duration: 38.40595ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:01:20.383462Z","caller":"traceutil/trace.go:171","msg":"trace[709714029] transaction","detail":"{read_only:false; response_revision:8610; number_of_response:1; }","duration":"178.233631ms","start":"2026-10-14T16:01:20.205209Z","end":"2026-10-14T16:01:20.383442Z","steps":["trace[709714029] 'process raft request' (duration: 178.100998ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:20.413765Z","caller":"traceutil/trace.go:171","msg":"trace[98679331] transaction","detail":"{read_only:false; response_revision:8611; number_of_response:1; }","duration":"207.655266ms","start":"2026-10-14T16:01:20.206086Z","end":"2026-10-14T16:01:20.413741Z","steps":["trace[98679331] 'process raft request' (duration: 204.786918ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:20.413817Z","caller":"traceutil/trace.go:171","msg":"trace[83616209] transaction","detail":"{read_only:false; response_revision:8612; number_of_response:1; }","duration":"206.735265ms","start":"2026-10-14T16:01:20.20706Z","end":"2026-10-14T16:01:20.413796Z","steps":["trace[83616209] 'process raft request' (duration: 206.655183ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:20.414105Z","caller":"traceutil/trace.go:171","msg":"trace[1205969843] transaction","detail":"{read_only:false; response_revision:8613; number_of_response:1; }","duration":"206.597492ms","start":"2026-10-14T16:01:20.207487Z","end":"2026-10-14T16:01:20.414084Z","steps":["trace[1205969843] 'process raft request' (duration: 206.277104ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:20.414163Z","caller":"traceutil/trace.go:171","msg":"trace[1504012543] linearizableReadLoop","detail":"{readStateIndex:8917; appliedIndex:8914; }","duration":"207.034472ms","start":"2026-10-14T16:01:20.2071Z","end":"2026-10-14T16:01:20.414135Z","steps":["trace[1504012543] 'read index received' (duration: 176.258974ms)","trace[1504012543] 'applied index is now lower than readState.Index' (duration: 30.774198ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:01:20.414233Z","caller":"traceutil/trace.go:171","msg":"trace[924396119] transaction","detail":"{read_only:false; response_revision:8614; number_of_response:1; }","duration":"206.657083ms","start":"2026-10-14T16:01:20.207555Z","end":"2026-10-14T16:01:20.414212Z","steps":["trace[924396119] 'process raft request' (duration: 206.439287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:20.414603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.480532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223833"} {"level":"info","ts":"2026-10-14T16:01:20.414647Z","caller":"traceutil/trace.go:171","msg":"trace[1371768602] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8614; }","duration":"207.572485ms","start":"2026-10-14T16:01:20.207064Z","end":"2026-10-14T16:01:20.414637Z","steps":["trace[1371768602] 'agreement among raft nodes before linearized reading' (duration: 207.161945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:56.214086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:55.849301Z","time spent":"364.77442ms","remote":"127.0.0.1:60294","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-14T16:01:56.214175Z","caller":"traceutil/trace.go:171","msg":"trace[1718788382] linearizableReadLoop","detail":"{readStateIndex:9168; appliedIndex:9166; }","duration":"197.649385ms","start":"2026-10-14T16:01:56.016495Z","end":"2026-10-14T16:01:56.214144Z","steps":["trace[1718788382] 'read index received' (duration: 125.642978ms)","trace[1718788382] 'applied index is now lower than readState.Index' (duration: 72.004827ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:01:56.214346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.83632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:01:56.214402Z","caller":"traceutil/trace.go:171","msg":"trace[1108340688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8853; }","duration":"197.946813ms","start":"2026-10-14T16:01:56.016441Z","end":"2026-10-14T16:01:56.214387Z","steps":["trace[1108340688] 'agreement among raft nodes before linearized reading' (duration: 197.81152ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:56.214571Z","caller":"traceutil/trace.go:171","msg":"trace[725689161] transaction","detail":"{read_only:false; response_revision:8853; number_of_response:1; }","duration":"294.846584ms","start":"2026-10-14T16:01:55.919695Z","end":"2026-10-14T16:01:56.214542Z","steps":["trace[725689161] 'process raft request' (duration: 294.28605ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:57.204084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.003477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T16:01:57.204374Z","caller":"traceutil/trace.go:171","msg":"trace[1187042897] transaction","detail":"{read_only:false; response_revision:8860; number_of_response:1; }","duration":"474.197885ms","start":"2026-10-14T16:01:56.730159Z","end":"2026-10-14T16:01:57.204357Z","steps":["trace[1187042897] 'process raft request' (duration: 474.098152ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:57.204491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:56.73014Z","time spent":"474.314858ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:01:57.204476Z","caller":"traceutil/trace.go:171","msg":"trace[1518799091] transaction","detail":"{read_only:false; response_revision:8859; number_of_response:1; }","duration":"474.698607ms","start":"2026-10-14T16:01:56.729723Z","end":"2026-10-14T16:01:57.204422Z","steps":["trace[1518799091] 'process raft request' (duration: 179.234619ms)","trace[1518799091] 'compare' (duration: 294.260259ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:01:57.20467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:56.729702Z","time spent":"474.879401ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:01:57.591079Z","caller":"traceutil/trace.go:171","msg":"trace[327766969] linearizableReadLoop","detail":"{readStateIndex:9176; appliedIndex:9173; }","duration":"574.536959ms","start":"2026-10-14T16:01:57.016515Z","end":"2026-10-14T16:01:57.591052Z","steps":["trace[327766969] 'read index received' (duration: 179.438204ms)","trace[327766969] 'applied index is now lower than readState.Index' (duration: 395.097825ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:01:57.591114Z","caller":"traceutil/trace.go:171","msg":"trace[504128294] transaction","detail":"{read_only:false; response_revision:8861; number_of_response:1; }","duration":"638.674885ms","start":"2026-10-14T16:01:56.952404Z","end":"2026-10-14T16:01:57.591079Z","steps":["trace[504128294] 'process raft request' (duration: 638.435659ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:01:57.591145Z","caller":"traceutil/trace.go:171","msg":"trace[558096760] transaction","detail":"{read_only:false; response_revision:8862; number_of_response:1; }","duration":"489.449194ms","start":"2026-10-14T16:01:57.101665Z","end":"2026-10-14T16:01:57.591114Z","steps":["trace[558096760] 'process raft request' (duration: 489.345001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:57.591238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.708733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:01:57.591273Z","caller":"traceutil/trace.go:171","msg":"trace[532055814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8862; }","duration":"574.786205ms","start":"2026-10-14T16:01:57.016475Z","end":"2026-10-14T16:01:57.591261Z","steps":["trace[532055814] 'agreement among raft nodes before linearized reading' (duration: 574.709503ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:57.591278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:56.952376Z","time spent":"638.814849ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:01:57.591276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:57.101646Z","time spent":"489.557777ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:01:57.591311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:57.016458Z","time spent":"574.845936ms","remote":"127.0.0.1:60266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T16:01:57.59148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.04348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:01:57.591545Z","caller":"traceutil/trace.go:171","msg":"trace[790280344] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8862; }","duration":"136.134113ms","start":"2026-10-14T16:01:57.455393Z","end":"2026-10-14T16:01:57.591527Z","steps":["trace[790280344] 'agreement among raft nodes before linearized reading' (duration: 136.02057ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:57.591528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.278667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-14T16:01:57.59165Z","caller":"traceutil/trace.go:171","msg":"trace[18322660] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8862; }","duration":"540.480862ms","start":"2026-10-14T16:01:57.051139Z","end":"2026-10-14T16:01:57.59162Z","steps":["trace[18322660] 'agreement among raft nodes before linearized reading' (duration: 540.082272ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:01:57.591723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:01:57.051119Z","time spent":"540.591065ms","remote":"127.0.0.1:60494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-14T16:02:03.513483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.365652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T16:02:03.513534Z","caller":"traceutil/trace.go:171","msg":"trace[1586278879] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:8932; }","duration":"146.453853ms","start":"2026-10-14T16:02:03.367068Z","end":"2026-10-14T16:02:03.513521Z","steps":["trace[1586278879] 'count revisions from in-memory index tree' (duration: 146.271549ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:02:03.909176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.484301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T16:02:03.90931Z","caller":"traceutil/trace.go:171","msg":"trace[12450747] transaction","detail":"{read_only:false; response_revision:8934; number_of_response:1; }","duration":"293.120921ms","start":"2026-10-14T16:02:03.616164Z","end":"2026-10-14T16:02:03.909285Z","steps":["trace[12450747] 'process raft request' (duration: 128.425145ms)","trace[12450747] 'compare' (duration: 164.03525ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:02:03.926901Z","caller":"traceutil/trace.go:171","msg":"trace[874768216] linearizableReadLoop","detail":"{readStateIndex:9255; appliedIndex:9252; }","duration":"271.010216ms","start":"2026-10-14T16:02:03.655852Z","end":"2026-10-14T16:02:03.926862Z","steps":["trace[874768216] 'read index received' (duration: 88.793235ms)","trace[874768216] 'applied index is now lower than readState.Index' (duration: 182.216171ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:02:03.927089Z","caller":"traceutil/trace.go:171","msg":"trace[1653709907] transaction","detail":"{read_only:false; response_revision:8935; number_of_response:1; }","duration":"310.926904ms","start":"2026-10-14T16:02:03.616143Z","end":"2026-10-14T16:02:03.92707Z","steps":["trace[1653709907] 'process raft request' (duration: 310.564785ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:02:03.927129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.263972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-10-14T16:02:03.927218Z","caller":"traceutil/trace.go:171","msg":"trace[1627890988] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:8936; }","duration":"271.389785ms","start":"2026-10-14T16:02:03.655808Z","end":"2026-10-14T16:02:03.927198Z","steps":["trace[1627890988] 'agreement among raft nodes before linearized reading' (duration: 271.146769ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:02:03.927596Z","caller":"traceutil/trace.go:171","msg":"trace[1714130338] transaction","detail":"{read_only:false; response_revision:8936; number_of_response:1; }","duration":"309.181111ms","start":"2026-10-14T16:02:03.618403Z","end":"2026-10-14T16:02:03.927584Z","steps":["trace[1714130338] 'process raft request' (duration: 308.413433ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:02:03.927669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:02:03.618385Z","time spent":"309.244703ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:02:03.927221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:02:03.616118Z","time spent":"311.022377ms","remote":"127.0.0.1:60494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4638,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:02:17.66315Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024aa.wal"} {"level":"info","ts":"2026-10-14T16:03:25.132146Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3fd97315619643d2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T16:03:25.143363Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-14T16:03:25.143465Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-14T16:03:26.191613Z","caller":"traceutil/trace.go:171","msg":"trace[2095767959] transaction","detail":"{read_only:false; response_revision:9684; number_of_response:1; }","duration":"186.022834ms","start":"2026-10-14T16:03:26.005562Z","end":"2026-10-14T16:03:26.191585Z","steps":["trace[2095767959] 'process raft request' (duration: 106.198552ms)","trace[2095767959] 'compare' (duration: 79.669479ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:03:26.442573Z","caller":"traceutil/trace.go:171","msg":"trace[507691112] linearizableReadLoop","detail":"{readStateIndex:10029; appliedIndex:10026; }","duration":"106.102029ms","start":"2026-10-14T16:03:26.336445Z","end":"2026-10-14T16:03:26.442547Z","steps":["trace[507691112] 'read index received' (duration: 5.993255ms)","trace[507691112] 'applied index is now lower than readState.Index' (duration: 100.107874ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:03:26.442629Z","caller":"traceutil/trace.go:171","msg":"trace[1134346633] transaction","detail":"{read_only:false; response_revision:9685; number_of_response:1; }","duration":"247.225196ms","start":"2026-10-14T16:03:26.195361Z","end":"2026-10-14T16:03:26.442586Z","steps":["trace[1134346633] 'process raft request' (duration: 147.071271ms)","trace[1134346633] 'compare' (duration: 99.690243ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:03:26.442666Z","caller":"traceutil/trace.go:171","msg":"trace[855193369] transaction","detail":"{read_only:false; response_revision:9686; number_of_response:1; }","duration":"220.257583ms","start":"2026-10-14T16:03:26.222379Z","end":"2026-10-14T16:03:26.442636Z","steps":["trace[855193369] 'process raft request' (duration: 220.061988ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:03:26.442724Z","caller":"traceutil/trace.go:171","msg":"trace[90719587] transaction","detail":"{read_only:false; response_revision:9687; number_of_response:1; }","duration":"206.596892ms","start":"2026-10-14T16:03:26.236091Z","end":"2026-10-14T16:03:26.442688Z","steps":["trace[90719587] 'process raft request' (duration: 206.416598ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:03:26.44278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.325095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-rgw-ceph-a-699b8bdb59-8kwmj\" ","response":"range_response_count:1 size:19075"} {"level":"info","ts":"2026-10-14T16:03:26.442816Z","caller":"traceutil/trace.go:171","msg":"trace[766837870] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-rgw-ceph-a-699b8bdb59-8kwmj; range_end:; response_count:1; response_revision:9687; }","duration":"106.408786ms","start":"2026-10-14T16:03:26.336396Z","end":"2026-10-14T16:03:26.442804Z","steps":["trace[766837870] 'agreement among raft nodes before linearized reading' (duration: 106.244042ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:03:26.626442Z","caller":"traceutil/trace.go:171","msg":"trace[1551832284] transaction","detail":"{read_only:false; response_revision:9689; number_of_response:1; }","duration":"168.782687ms","start":"2026-10-14T16:03:26.457616Z","end":"2026-10-14T16:03:26.626399Z","steps":["trace[1551832284] 'process raft request' (duration: 142.966752ms)","trace[1551832284] 'compare' (duration: 25.636461ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:03:26.763366Z","caller":"traceutil/trace.go:171","msg":"trace[1405280201] transaction","detail":"{read_only:false; response_revision:9691; number_of_response:1; }","duration":"129.227508ms","start":"2026-10-14T16:03:26.634109Z","end":"2026-10-14T16:03:26.763336Z","steps":["trace[1405280201] 'process raft request' (duration: 107.41753ms)","trace[1405280201] 'compare' (duration: 21.674815ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:05:12.255013Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8040} {"level":"info","ts":"2026-10-14T16:05:12.32953Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8040,"took":"71.274516ms","hash":3013443032,"current-db-size-bytes":55525376,"current-db-size":"56 MB","current-db-size-in-use-bytes":29118464,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-14T16:05:12.329708Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3013443032,"revision":8040,"compact-revision":5845} {"level":"info","ts":"2026-10-14T16:07:06.992737Z","caller":"traceutil/trace.go:171","msg":"trace[2116233342] linearizableReadLoop","detail":"{readStateIndex:11975; appliedIndex:11974; }","duration":"127.968504ms","start":"2026-10-14T16:07:06.864733Z","end":"2026-10-14T16:07:06.992702Z","steps":["trace[2116233342] 'read index received' (duration: 111.233674ms)","trace[2116233342] 'applied index is now lower than readState.Index' (duration: 16.73358ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:07:06.993153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.304825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cf2zq\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-14T16:07:06.993235Z","caller":"traceutil/trace.go:171","msg":"trace[500625732] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cf2zq; range_end:; response_count:1; response_revision:11577; }","duration":"116.443218ms","start":"2026-10-14T16:07:06.876777Z","end":"2026-10-14T16:07:06.99322Z","steps":["trace[500625732] 'agreement among raft nodes before linearized reading' (duration: 116.161501ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:07:06.993302Z","caller":"traceutil/trace.go:171","msg":"trace[988486698] transaction","detail":"{read_only:false; response_revision:11577; number_of_response:1; }","duration":"483.295183ms","start":"2026-10-14T16:07:06.509988Z","end":"2026-10-14T16:07:06.993283Z","steps":["trace[988486698] 'process raft request' (duration: 466.047901ms)","trace[988486698] 'compare' (duration: 16.084074ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:07:06.993235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.496226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-14T16:07:06.993371Z","caller":"traceutil/trace.go:171","msg":"trace[235610553] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:11577; }","duration":"128.66482ms","start":"2026-10-14T16:07:06.864679Z","end":"2026-10-14T16:07:06.993344Z","steps":["trace[235610553] 'agreement among raft nodes before linearized reading' (duration: 128.489186ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:07:06.993453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:07:06.509958Z","time spent":"483.383065ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:07:07.193192Z","caller":"traceutil/trace.go:171","msg":"trace[1612190124] linearizableReadLoop","detail":"{readStateIndex:11977; appliedIndex:11975; }","duration":"194.597444ms","start":"2026-10-14T16:07:06.998564Z","end":"2026-10-14T16:07:07.193161Z","steps":["trace[1612190124] 'read index received' (duration: 45.459295ms)","trace[1612190124] 'applied index is now lower than readState.Index' (duration: 149.136779ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:07:07.193259Z","caller":"traceutil/trace.go:171","msg":"trace[1349073015] transaction","detail":"{read_only:false; response_revision:11579; number_of_response:1; }","duration":"194.839439ms","start":"2026-10-14T16:07:06.998384Z","end":"2026-10-14T16:07:07.193223Z","steps":["trace[1349073015] 'process raft request' (duration: 169.222188ms)","trace[1349073015] 'compare' (duration: 25.290173ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:07:07.193418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.659325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:07:07.193475Z","caller":"traceutil/trace.go:171","msg":"trace[1102919531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11579; }","duration":"176.803409ms","start":"2026-10-14T16:07:07.016654Z","end":"2026-10-14T16:07:07.193457Z","steps":["trace[1102919531] 'agreement among raft nodes before linearized reading' (duration: 176.670576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:07:07.193857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.149024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T16:07:07.193856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.290647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T16:07:07.193866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.514463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:07:07.193949Z","caller":"traceutil/trace.go:171","msg":"trace[1327815849] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11579; }","duration":"114.250656ms","start":"2026-10-14T16:07:07.07965Z","end":"2026-10-14T16:07:07.1939Z","steps":["trace[1327815849] 'agreement among raft nodes before linearized reading' (duration: 114.155974ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:07:07.193962Z","caller":"traceutil/trace.go:171","msg":"trace[1604021275] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11579; }","duration":"114.37637ms","start":"2026-10-14T16:07:07.079532Z","end":"2026-10-14T16:07:07.193908Z","steps":["trace[1604021275] 'agreement among raft nodes before linearized reading' (duration: 114.283947ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:07:07.193963Z","caller":"traceutil/trace.go:171","msg":"trace[307497561] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11579; }","duration":"114.662646ms","start":"2026-10-14T16:07:07.079279Z","end":"2026-10-14T16:07:07.193942Z","steps":["trace[307497561] 'agreement among raft nodes before linearized reading' (duration: 114.528423ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:07:07.194084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.249716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T16:07:07.194111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.533046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:48 size:447664"} {"level":"info","ts":"2026-10-14T16:07:07.194134Z","caller":"traceutil/trace.go:171","msg":"trace[614561994] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11579; }","duration":"114.321208ms","start":"2026-10-14T16:07:07.079798Z","end":"2026-10-14T16:07:07.19412Z","steps":["trace[614561994] 'agreement among raft nodes before linearized reading' (duration: 114.240826ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:07:07.194169Z","caller":"traceutil/trace.go:171","msg":"trace[886525716] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:48; response_revision:11579; }","duration":"195.597697ms","start":"2026-10-14T16:07:06.998557Z","end":"2026-10-14T16:07:07.194155Z","steps":["trace[886525716] 'agreement among raft nodes before linearized reading' (duration: 194.700456ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:07:07.194184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.045658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-10-14T16:07:07.194237Z","caller":"traceutil/trace.go:171","msg":"trace[145154181] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11579; }","duration":"113.122659ms","start":"2026-10-14T16:07:07.081098Z","end":"2026-10-14T16:07:07.194221Z","steps":["trace[145154181] 'agreement among raft nodes before linearized reading' (duration: 112.932364ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:07:07.447688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.272339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T16:07:07.447858Z","caller":"traceutil/trace.go:171","msg":"trace[109821729] transaction","detail":"{read_only:false; response_revision:11580; number_of_response:1; }","duration":"160.688155ms","start":"2026-10-14T16:07:07.287138Z","end":"2026-10-14T16:07:07.447827Z","steps":["trace[109821729] 'process raft request' (duration: 30.111699ms)","trace[109821729] 'compare' (duration: 130.024542ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:07:07.496092Z","caller":"traceutil/trace.go:171","msg":"trace[1374314464] transaction","detail":"{read_only:false; response_revision:11581; number_of_response:1; }","duration":"206.417266ms","start":"2026-10-14T16:07:07.289652Z","end":"2026-10-14T16:07:07.49607Z","steps":["trace[1374314464] 'process raft request' (duration: 206.213491ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:07:07.49631Z","caller":"traceutil/trace.go:171","msg":"trace[696782221] transaction","detail":"{read_only:false; response_revision:11582; number_of_response:1; }","duration":"199.184432ms","start":"2026-10-14T16:07:07.297087Z","end":"2026-10-14T16:07:07.496271Z","steps":["trace[696782221] 'process raft request' (duration: 198.930106ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:10:12.263452Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10513} {"level":"info","ts":"2026-10-14T16:10:12.362027Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10513,"took":"95.020029ms","hash":1047666307,"current-db-size-bytes":55525376,"current-db-size":"56 MB","current-db-size-in-use-bytes":28012544,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T16:10:12.362112Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1047666307,"revision":10513,"compact-revision":8040} {"level":"info","ts":"2026-10-14T16:12:54.962701Z","caller":"traceutil/trace.go:171","msg":"trace[119581891] linearizableReadLoop","detail":"{readStateIndex:14432; appliedIndex:14431; }","duration":"113.351685ms","start":"2026-10-14T16:12:54.849335Z","end":"2026-10-14T16:12:54.962687Z","steps":["trace[119581891] 'read index received' (duration: 101.56706ms)","trace[119581891] 'applied index is now lower than readState.Index' (duration: 11.783705ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:12:54.962737Z","caller":"traceutil/trace.go:171","msg":"trace[1990584397] transaction","detail":"{read_only:false; response_revision:13953; number_of_response:1; }","duration":"117.165365ms","start":"2026-10-14T16:12:54.845534Z","end":"2026-10-14T16:12:54.962699Z","steps":["trace[1990584397] 'process raft request' (duration: 105.328658ms)","trace[1990584397] 'compare' (duration: 11.57612ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:12:54.962975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.627111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-14T16:12:54.963048Z","caller":"traceutil/trace.go:171","msg":"trace[1029380401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13953; }","duration":"113.711123ms","start":"2026-10-14T16:12:54.849319Z","end":"2026-10-14T16:12:54.96303Z","steps":["trace[1029380401] 'agreement among raft nodes before linearized reading' (duration: 113.436437ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:12:55.010478Z","caller":"traceutil/trace.go:171","msg":"trace[1560941497] transaction","detail":"{read_only:false; response_revision:13954; number_of_response:1; }","duration":"154.765942ms","start":"2026-10-14T16:12:54.855666Z","end":"2026-10-14T16:12:55.010432Z","steps":["trace[1560941497] 'process raft request' (duration: 154.551837ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:12:55.010778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.356889ms","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-10-14T16:12:55.010859Z","caller":"traceutil/trace.go:171","msg":"trace[1885322560] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13955; }","duration":"156.484812ms","start":"2026-10-14T16:12:54.854353Z","end":"2026-10-14T16:12:55.010837Z","steps":["trace[1885322560] 'agreement among raft nodes before linearized reading' (duration: 156.257917ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:12:55.011336Z","caller":"traceutil/trace.go:171","msg":"trace[77748201] transaction","detail":"{read_only:false; response_revision:13955; number_of_response:1; }","duration":"111.317228ms","start":"2026-10-14T16:12:54.900005Z","end":"2026-10-14T16:12:55.011323Z","steps":["trace[77748201] 'process raft request' (duration: 110.374056ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:12:58.641274Z","caller":"traceutil/trace.go:171","msg":"trace[1386237037] linearizableReadLoop","detail":"{readStateIndex:14451; appliedIndex:14450; }","duration":"178.089396ms","start":"2026-10-14T16:12:58.463162Z","end":"2026-10-14T16:12:58.641251Z","steps":["trace[1386237037] 'read index received' (duration: 177.923702ms)","trace[1386237037] 'applied index is now lower than readState.Index' (duration: 164.364µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:12:58.641333Z","caller":"traceutil/trace.go:171","msg":"trace[1982805201] transaction","detail":"{read_only:false; response_revision:13971; number_of_response:1; }","duration":"179.681363ms","start":"2026-10-14T16:12:58.461623Z","end":"2026-10-14T16:12:58.641305Z","steps":["trace[1982805201] 'process raft request' (duration: 179.490859ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:12:58.641432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.25434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:12:58.641465Z","caller":"traceutil/trace.go:171","msg":"trace[203709448] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:13971; }","duration":"178.332742ms","start":"2026-10-14T16:12:58.463121Z","end":"2026-10-14T16:12:58.641454Z","steps":["trace[203709448] 'agreement among raft nodes before linearized reading' (duration: 178.24461ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:13:19.868067Z","caller":"traceutil/trace.go:171","msg":"trace[1703855749] linearizableReadLoop","detail":"{readStateIndex:14574; appliedIndex:14573; }","duration":"246.153705ms","start":"2026-10-14T16:13:19.621887Z","end":"2026-10-14T16:13:19.868041Z","steps":["trace[1703855749] 'read index received' (duration: 245.899149ms)","trace[1703855749] 'applied index is now lower than readState.Index' (duration: 253.406µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:13:19.868136Z","caller":"traceutil/trace.go:171","msg":"trace[562097746] transaction","detail":"{read_only:false; response_revision:14090; number_of_response:1; }","duration":"305.790846ms","start":"2026-10-14T16:13:19.562311Z","end":"2026-10-14T16:13:19.868102Z","steps":["trace[562097746] 'process raft request' (duration: 305.578991ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:13:19.868223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.315289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:13:19.868269Z","caller":"traceutil/trace.go:171","msg":"trace[864406006] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:14090; }","duration":"246.436012ms","start":"2026-10-14T16:13:19.62182Z","end":"2026-10-14T16:13:19.868256Z","steps":["trace[864406006] 'agreement among raft nodes before linearized reading' (duration: 246.325989ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:13:19.868279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:13:19.562283Z","time spent":"305.92962ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:13:19.868406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.056536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-14T16:13:19.868455Z","caller":"traceutil/trace.go:171","msg":"trace[1215265774] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:14090; }","duration":"238.186159ms","start":"2026-10-14T16:13:19.630255Z","end":"2026-10-14T16:13:19.868441Z","steps":["trace[1215265774] 'agreement among raft nodes before linearized reading' (duration: 237.934763ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:13:35.453016Z","caller":"traceutil/trace.go:171","msg":"trace[817174703] transaction","detail":"{read_only:false; response_revision:14186; number_of_response:1; }","duration":"112.948776ms","start":"2026-10-14T16:13:35.34004Z","end":"2026-10-14T16:13:35.452988Z","steps":["trace[817174703] 'process raft request' (duration: 112.772382ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:13:36.376736Z","caller":"traceutil/trace.go:171","msg":"trace[718133839] linearizableReadLoop","detail":"{readStateIndex:14679; appliedIndex:14678; }","duration":"111.82512ms","start":"2026-10-14T16:13:36.264885Z","end":"2026-10-14T16:13:36.37671Z","steps":["trace[718133839] 'read index received' (duration: 111.652616ms)","trace[718133839] 'applied index is now lower than readState.Index' (duration: 171.084µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:13:36.376796Z","caller":"traceutil/trace.go:171","msg":"trace[1934041811] transaction","detail":"{read_only:false; response_revision:14189; number_of_response:1; }","duration":"242.433148ms","start":"2026-10-14T16:13:36.134328Z","end":"2026-10-14T16:13:36.376761Z","steps":["trace[1934041811] 'process raft request' (duration: 242.193013ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:13:36.376971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.060186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-14T16:13:36.377035Z","caller":"traceutil/trace.go:171","msg":"trace[1015850591] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14189; }","duration":"112.200938ms","start":"2026-10-14T16:13:36.264822Z","end":"2026-10-14T16:13:36.377023Z","steps":["trace[1015850591] 'agreement among raft nodes before linearized reading' (duration: 111.988794ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:13:36.586875Z","caller":"traceutil/trace.go:171","msg":"trace[1400382955] transaction","detail":"{read_only:false; response_revision:14190; number_of_response:1; }","duration":"205.371083ms","start":"2026-10-14T16:13:36.381455Z","end":"2026-10-14T16:13:36.586826Z","steps":["trace[1400382955] 'process raft request' (duration: 176.892198ms)","trace[1400382955] 'compare' (duration: 28.155508ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:13:52.349715Z","caller":"traceutil/trace.go:171","msg":"trace[2020961247] linearizableReadLoop","detail":"{readStateIndex:14863; appliedIndex:14862; }","duration":"333.426061ms","start":"2026-10-14T16:13:52.016265Z","end":"2026-10-14T16:13:52.349692Z","steps":["trace[2020961247] 'read index received' (duration: 333.237137ms)","trace[2020961247] 'applied index is now lower than readState.Index' (duration: 188.084µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:13:52.349835Z","caller":"traceutil/trace.go:171","msg":"trace[1219349229] transaction","detail":"{read_only:false; response_revision:14364; number_of_response:1; }","duration":"409.353754ms","start":"2026-10-14T16:13:51.940469Z","end":"2026-10-14T16:13:52.349823Z","steps":["trace[1219349229] 'process raft request' (duration: 409.085287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:13:52.349957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:13:51.940441Z","time spent":"409.449455ms","remote":"127.0.0.1:37624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:13:52.350259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.994615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T16:13:52.350283Z","caller":"traceutil/trace.go:171","msg":"trace[1955542082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14364; }","duration":"334.062456ms","start":"2026-10-14T16:13:52.016214Z","end":"2026-10-14T16:13:52.350277Z","steps":["trace[1955542082] 'agreement among raft nodes before linearized reading' (duration: 334.020225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:13:52.350301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:13:52.01619Z","time spent":"334.106618ms","remote":"127.0.0.1:60266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-14T16:13:52.351056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.95054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225408"} {"level":"info","ts":"2026-10-14T16:13:52.351118Z","caller":"traceutil/trace.go:171","msg":"trace[1937897255] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:14364; }","duration":"121.061232ms","start":"2026-10-14T16:13:52.230042Z","end":"2026-10-14T16:13:52.351104Z","steps":["trace[1937897255] 'agreement among raft nodes before linearized reading' (duration: 120.024297ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:15:12.272591Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12798} {"level":"info","ts":"2026-10-14T16:15:12.364852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12798,"took":"89.640537ms","hash":1421784761,"current-db-size-bytes":55525376,"current-db-size":"56 MB","current-db-size-in-use-bytes":30347264,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T16:15:12.365448Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1421784761,"revision":12798,"compact-revision":10513} {"level":"info","ts":"2026-10-14T16:15:31.577122Z","caller":"traceutil/trace.go:171","msg":"trace[902682616] linearizableReadLoop","detail":"{readStateIndex:15869; appliedIndex:15868; }","duration":"149.980124ms","start":"2026-10-14T16:15:31.427113Z","end":"2026-10-14T16:15:31.577093Z","steps":["trace[902682616] 'read index received' (duration: 149.669676ms)","trace[902682616] 'applied index is now lower than readState.Index' (duration: 309.388µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:15:31.577213Z","caller":"traceutil/trace.go:171","msg":"trace[1035800598] transaction","detail":"{read_only:false; response_revision:15343; number_of_response:1; }","duration":"162.289472ms","start":"2026-10-14T16:15:31.414882Z","end":"2026-10-14T16:15:31.577172Z","steps":["trace[1035800598] 'process raft request' (duration: 162.013646ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:15:31.577432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.269191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-14T16:15:31.577477Z","caller":"traceutil/trace.go:171","msg":"trace[1038927079] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15343; }","duration":"150.368703ms","start":"2026-10-14T16:15:31.427095Z","end":"2026-10-14T16:15:31.577464Z","steps":["trace[1038927079] 'agreement among raft nodes before linearized reading' (duration: 150.155438ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:15:31.577572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.070385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-wsctf\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-10-14T16:15:31.577656Z","caller":"traceutil/trace.go:171","msg":"trace[1567615188] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-wsctf; range_end:; response_count:1; response_revision:15343; }","duration":"135.224468ms","start":"2026-10-14T16:15:31.442409Z","end":"2026-10-14T16:15:31.577634Z","steps":["trace[1567615188] 'agreement among raft nodes before linearized reading' (duration: 134.942942ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:15:31.754174Z","caller":"traceutil/trace.go:171","msg":"trace[1997721583] transaction","detail":"{read_only:false; response_revision:15345; number_of_response:1; }","duration":"109.531956ms","start":"2026-10-14T16:15:31.644601Z","end":"2026-10-14T16:15:31.754133Z","steps":["trace[1997721583] 'process raft request' (duration: 47.872031ms)","trace[1997721583] 'compare' (duration: 61.46921ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:20:12.283504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15245} {"level":"info","ts":"2026-10-14T16:20:12.395654Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15245,"took":"108.576291ms","hash":2552280940,"current-db-size-bytes":55525376,"current-db-size":"56 MB","current-db-size-in-use-bytes":32292864,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T16:20:12.395724Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2552280940,"revision":15245,"compact-revision":12798} {"level":"info","ts":"2026-10-14T16:21:10.004885Z","caller":"traceutil/trace.go:171","msg":"trace[704888283] transaction","detail":"{read_only:false; response_revision:17878; number_of_response:1; }","duration":"176.785763ms","start":"2026-10-14T16:21:09.828075Z","end":"2026-10-14T16:21:10.004861Z","steps":["trace[704888283] 'process raft request' (duration: 175.928113ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:21:10.35798Z","caller":"traceutil/trace.go:171","msg":"trace[70247342] transaction","detail":"{read_only:false; response_revision:17880; number_of_response:1; }","duration":"280.199226ms","start":"2026-10-14T16:21:10.077759Z","end":"2026-10-14T16:21:10.357959Z","steps":["trace[70247342] 'process raft request' (duration: 242.306988ms)","trace[70247342] 'compare' (duration: 37.452588ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:21:10.892739Z","caller":"traceutil/trace.go:171","msg":"trace[2089133060] linearizableReadLoop","detail":"{readStateIndex:18491; appliedIndex:18490; }","duration":"373.50219ms","start":"2026-10-14T16:21:10.519215Z","end":"2026-10-14T16:21:10.892717Z","steps":["trace[2089133060] 'read index received' (duration: 318.598288ms)","trace[2089133060] 'applied index is now lower than readState.Index' (duration: 54.903042ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:21:10.892866Z","caller":"traceutil/trace.go:171","msg":"trace[1196085746] transaction","detail":"{read_only:false; response_revision:17881; number_of_response:1; }","duration":"393.31263ms","start":"2026-10-14T16:21:10.499528Z","end":"2026-10-14T16:21:10.892841Z","steps":["trace[1196085746] 'process raft request' (duration: 338.267484ms)","trace[1196085746] 'compare' (duration: 54.822311ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:21:10.892887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.660534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:21:10.89293Z","caller":"traceutil/trace.go:171","msg":"trace[1142859432] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:17881; }","duration":"373.718996ms","start":"2026-10-14T16:21:10.5192Z","end":"2026-10-14T16:21:10.89292Z","steps":["trace[1142859432] 'agreement among raft nodes before linearized reading' (duration: 373.637374ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:10.893341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:21:10.519145Z","time spent":"374.184477ms","remote":"127.0.0.1:47534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-10-14T16:21:10.892985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.964623ms","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-10-14T16:21:10.893503Z","caller":"traceutil/trace.go:171","msg":"trace[712087482] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17881; }","duration":"181.526076ms","start":"2026-10-14T16:21:10.711968Z","end":"2026-10-14T16:21:10.893495Z","steps":["trace[712087482] 'agreement among raft nodes before linearized reading' (duration: 180.877331ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:21:10.893026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:21:10.499503Z","time spent":"393.420033ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:21:11.066708Z","caller":"traceutil/trace.go:171","msg":"trace[1593235421] transaction","detail":"{read_only:false; response_revision:17883; number_of_response:1; }","duration":"166.860529ms","start":"2026-10-14T16:21:10.899815Z","end":"2026-10-14T16:21:11.066675Z","steps":["trace[1593235421] 'process raft request' (duration: 113.543974ms)","trace[1593235421] 'compare' (duration: 52.944616ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:21:11.066722Z","caller":"traceutil/trace.go:171","msg":"trace[1241801856] linearizableReadLoop","detail":"{readStateIndex:18493; appliedIndex:18491; }","duration":"164.037051ms","start":"2026-10-14T16:21:10.902655Z","end":"2026-10-14T16:21:11.066692Z","steps":["trace[1241801856] 'read index received' (duration: 62.592845ms)","trace[1241801856] 'applied index is now lower than readState.Index' (duration: 101.441426ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:21:11.083408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.731337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945946"} {"level":"info","ts":"2026-10-14T16:21:11.083482Z","caller":"traceutil/trace.go:171","msg":"trace[66892943] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:17883; }","duration":"180.83511ms","start":"2026-10-14T16:21:10.902629Z","end":"2026-10-14T16:21:11.083464Z","steps":["trace[66892943] 'agreement among raft nodes before linearized reading' (duration: 164.175235ms)","trace[66892943] 'range keys from in-memory index tree' (duration: 15.827026ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:23:57.869208Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3fd97315619643d2","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T16:23:57.875631Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-14T16:23:57.875786Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-14T16:25:12.292946Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17466} {"level":"info","ts":"2026-10-14T16:25:12.397069Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17466,"took":"100.576104ms","hash":3519447345,"current-db-size-bytes":55525376,"current-db-size":"56 MB","current-db-size-in-use-bytes":34693120,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-14T16:25:12.39713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3519447345,"revision":17466,"compact-revision":15245} {"level":"warn","ts":"2026-10-14T16:26:21.724827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.307949ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-14T16:26:21.724944Z","caller":"traceutil/trace.go:171","msg":"trace[239282823] linearizableReadLoop","detail":"{readStateIndex:21069; appliedIndex:21068; }","duration":"300.471674ms","start":"2026-10-14T16:26:21.424453Z","end":"2026-10-14T16:26:21.724924Z","steps":["trace[239282823] 'read index received' (duration: 169.047462ms)","trace[239282823] 'applied index is now lower than readState.Index' (duration: 131.422582ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:26:21.725142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.691379ms","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-10-14T16:26:21.72518Z","caller":"traceutil/trace.go:171","msg":"trace[586808961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20375; }","duration":"300.773561ms","start":"2026-10-14T16:26:21.424395Z","end":"2026-10-14T16:26:21.725169Z","steps":["trace[586808961] 'agreement among raft nodes before linearized reading' (duration: 300.622607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:21.725218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:26:21.424365Z","time spent":"300.843233ms","remote":"127.0.0.1:60588","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":"warn","ts":"2026-10-14T16:26:21.725453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.643365ms","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-10-14T16:26:21.725686Z","caller":"traceutil/trace.go:171","msg":"trace[632199701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20375; }","duration":"213.89288ms","start":"2026-10-14T16:26:21.511771Z","end":"2026-10-14T16:26:21.725664Z","steps":["trace[632199701] 'agreement among raft nodes before linearized reading' (duration: 213.374298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:47.661093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.269734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T16:26:47.661211Z","caller":"traceutil/trace.go:171","msg":"trace[1085430257] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:20508; }","duration":"108.438648ms","start":"2026-10-14T16:26:47.552748Z","end":"2026-10-14T16:26:47.661186Z","steps":["trace[1085430257] 'count revisions from in-memory index tree' (duration: 108.200783ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:47.661238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.533974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-14T16:26:47.661327Z","caller":"traceutil/trace.go:171","msg":"trace[1709970581] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20508; }","duration":"132.682437ms","start":"2026-10-14T16:26:47.528625Z","end":"2026-10-14T16:26:47.661308Z","steps":["trace[1709970581] 'range keys from in-memory index tree' (duration: 132.330329ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:53.070965Z","caller":"traceutil/trace.go:171","msg":"trace[253410720] linearizableReadLoop","detail":"{readStateIndex:21237; appliedIndex:21236; }","duration":"139.78183ms","start":"2026-10-14T16:26:52.931157Z","end":"2026-10-14T16:26:53.070939Z","steps":["trace[253410720] 'read index received' (duration: 139.476403ms)","trace[253410720] 'applied index is now lower than readState.Index' (duration: 304.337µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:26:53.071324Z","caller":"traceutil/trace.go:171","msg":"trace[1126088947] transaction","detail":"{read_only:false; response_revision:20537; number_of_response:1; }","duration":"165.451991ms","start":"2026-10-14T16:26:52.905841Z","end":"2026-10-14T16:26:53.071293Z","steps":["trace[1126088947] 'process raft request' (duration: 164.95269ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:53.071376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.20141ms","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-10-14T16:26:53.071497Z","caller":"traceutil/trace.go:171","msg":"trace[1227871457] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20537; }","duration":"140.308003ms","start":"2026-10-14T16:26:52.931119Z","end":"2026-10-14T16:26:53.071427Z","steps":["trace[1227871457] 'agreement among raft nodes before linearized reading' (duration: 140.1778ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:53.698548Z","caller":"traceutil/trace.go:171","msg":"trace[470261288] linearizableReadLoop","detail":"{readStateIndex:21238; appliedIndex:21237; }","duration":"144.436378ms","start":"2026-10-14T16:26:53.554052Z","end":"2026-10-14T16:26:53.698488Z","steps":["trace[470261288] 'read index received' (duration: 144.248383ms)","trace[470261288] 'applied index is now lower than readState.Index' (duration: 186.945µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:26:53.69876Z","caller":"traceutil/trace.go:171","msg":"trace[1664899986] transaction","detail":"{read_only:false; response_revision:20538; number_of_response:1; }","duration":"184.424089ms","start":"2026-10-14T16:26:53.514297Z","end":"2026-10-14T16:26:53.698721Z","steps":["trace[1664899986] 'process raft request' (duration: 184.064561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:53.698829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.762295ms","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:1936"} {"level":"info","ts":"2026-10-14T16:26:53.699473Z","caller":"traceutil/trace.go:171","msg":"trace[1502112285] 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:20538; }","duration":"145.39938ms","start":"2026-10-14T16:26:53.554037Z","end":"2026-10-14T16:26:53.699437Z","steps":["trace[1502112285] 'agreement among raft nodes before linearized reading' (duration: 144.586671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:53.868896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.498097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T16:26:53.869089Z","caller":"traceutil/trace.go:171","msg":"trace[873740487] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:20538; }","duration":"117.729172ms","start":"2026-10-14T16:26:53.751336Z","end":"2026-10-14T16:26:53.869066Z","steps":["trace[873740487] 'count revisions from in-memory index tree' (duration: 117.416675ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:54.035146Z","caller":"traceutil/trace.go:171","msg":"trace[1322078760] transaction","detail":"{read_only:false; response_revision:20539; number_of_response:1; }","duration":"122.370379ms","start":"2026-10-14T16:26:53.912746Z","end":"2026-10-14T16:26:54.035116Z","steps":["trace[1322078760] 'process raft request' (duration: 122.227476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:54.456939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.022362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:26:54.457043Z","caller":"traceutil/trace.go:171","msg":"trace[229239184] linearizableReadLoop","detail":"{readStateIndex:21240; appliedIndex:21239; }","duration":"519.912559ms","start":"2026-10-14T16:26:53.937115Z","end":"2026-10-14T16:26:54.457028Z","steps":["trace[229239184] 'read index received' (duration: 98.374637ms)","trace[229239184] 'applied index is now lower than readState.Index' (duration: 421.536912ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:26:54.457088Z","caller":"traceutil/trace.go:171","msg":"trace[537166087] transaction","detail":"{read_only:false; response_revision:20540; number_of_response:1; }","duration":"543.40517ms","start":"2026-10-14T16:26:53.913641Z","end":"2026-10-14T16:26:54.457046Z","steps":["trace[537166087] 'process raft request' (duration: 286.197763ms)","trace[537166087] 'compare' (duration: 242.009756ms)","trace[537166087] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:420; } (duration: 14.893643ms)"],"step_count":3} {"level":"warn","ts":"2026-10-14T16:26:54.457194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.201395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:26:54.457232Z","caller":"traceutil/trace.go:171","msg":"trace[461900528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20540; }","duration":"441.271297ms","start":"2026-10-14T16:26:54.01595Z","end":"2026-10-14T16:26:54.457221Z","steps":["trace[461900528] 'agreement among raft nodes before linearized reading' (duration: 441.208595ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:54.457234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:26:53.913624Z","time spent":"543.532603ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:26:54.457264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:26:54.015933Z","time spent":"441.323937ms","remote":"127.0.0.1:60266","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-10-14T16:26:54.45747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.337349ms","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-10-14T16:26:54.457658Z","caller":"traceutil/trace.go:171","msg":"trace[2039128632] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20540; }","duration":"520.565864ms","start":"2026-10-14T16:26:53.937064Z","end":"2026-10-14T16:26:54.45763Z","steps":["trace[2039128632] 'agreement among raft nodes before linearized reading' (duration: 520.068073ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:26:54.457771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:26:53.937042Z","time spent":"520.710957ms","remote":"127.0.0.1:60588","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":"warn","ts":"2026-10-14T16:26:54.756433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.764318ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:26:54.756567Z","caller":"traceutil/trace.go:171","msg":"trace[874963040] transaction","detail":"{read_only:false; response_revision:20541; number_of_response:1; }","duration":"294.412253ms","start":"2026-10-14T16:26:54.462109Z","end":"2026-10-14T16:26:54.756521Z","steps":["trace[874963040] 'process raft request' (duration: 191.472471ms)","trace[874963040] 'compare' (duration: 102.629635ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:26:54.760155Z","caller":"traceutil/trace.go:171","msg":"trace[342842722] transaction","detail":"{read_only:false; response_revision:20542; number_of_response:1; }","duration":"290.219877ms","start":"2026-10-14T16:26:54.469916Z","end":"2026-10-14T16:26:54.760136Z","steps":["trace[342842722] 'process raft request' (duration: 290.050513ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:54.760186Z","caller":"traceutil/trace.go:171","msg":"trace[379159389] transaction","detail":"{read_only:false; response_revision:20544; number_of_response:1; }","duration":"289.96063ms","start":"2026-10-14T16:26:54.4702Z","end":"2026-10-14T16:26:54.760161Z","steps":["trace[379159389] 'process raft request' (duration: 289.888019ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:54.760154Z","caller":"traceutil/trace.go:171","msg":"trace[1878586218] linearizableReadLoop","detail":"{readStateIndex:21244; appliedIndex:21240; }","duration":"230.513841ms","start":"2026-10-14T16:26:54.52962Z","end":"2026-10-14T16:26:54.760134Z","steps":["trace[1878586218] 'read index received' (duration: 124.114109ms)","trace[1878586218] 'applied index is now lower than readState.Index' (duration: 106.398292ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:26:54.760242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.603263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:26:54.760637Z","caller":"traceutil/trace.go:171","msg":"trace[346605943] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:20544; }","duration":"231.037403ms","start":"2026-10-14T16:26:54.529583Z","end":"2026-10-14T16:26:54.760621Z","steps":["trace[346605943] 'agreement among raft nodes before linearized reading' (duration: 230.607283ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:26:54.760183Z","caller":"traceutil/trace.go:171","msg":"trace[550670055] transaction","detail":"{read_only:false; response_revision:20543; number_of_response:1; }","duration":"290.075983ms","start":"2026-10-14T16:26:54.47008Z","end":"2026-10-14T16:26:54.760156Z","steps":["trace[550670055] 'process raft request' (duration: 289.966311ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:30:12.300528Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19973} {"level":"info","ts":"2026-10-14T16:30:12.436955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19973,"took":"132.267265ms","hash":1197164696,"current-db-size-bytes":55525376,"current-db-size":"56 MB","current-db-size-in-use-bytes":30593024,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T16:30:12.437025Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1197164696,"revision":19973,"compact-revision":17466} {"level":"info","ts":"2026-10-14T16:30:35.709598Z","caller":"traceutil/trace.go:171","msg":"trace[812365801] transaction","detail":"{read_only:false; response_revision:22912; number_of_response:1; }","duration":"358.419238ms","start":"2026-10-14T16:30:35.351142Z","end":"2026-10-14T16:30:35.709561Z","steps":["trace[812365801] 'process raft request' (duration: 321.760351ms)","trace[812365801] 'compare' (duration: 36.266077ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:30:35.709794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:30:35.351105Z","time spent":"358.582632ms","remote":"127.0.0.1:60588","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-10-14T16:30:35.70963Z","caller":"traceutil/trace.go:171","msg":"trace[1761729774] transaction","detail":"{read_only:false; response_revision:22913; number_of_response:1; }","duration":"137.418957ms","start":"2026-10-14T16:30:35.572186Z","end":"2026-10-14T16:30:35.709605Z","steps":["trace[1761729774] 'process raft request' (duration: 137.292384ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:33:46.994897Z","caller":"traceutil/trace.go:171","msg":"trace[2082093258] transaction","detail":"{read_only:false; response_revision:24822; number_of_response:1; }","duration":"170.513499ms","start":"2026-10-14T16:33:46.824353Z","end":"2026-10-14T16:33:46.994867Z","steps":["trace[2082093258] 'process raft request' (duration: 170.336955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:33:47.391435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.700748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:33:47.39152Z","caller":"traceutil/trace.go:171","msg":"trace[361724568] linearizableReadLoop","detail":"{readStateIndex:25646; appliedIndex:25645; }","duration":"321.621325ms","start":"2026-10-14T16:33:47.069884Z","end":"2026-10-14T16:33:47.391505Z","steps":["trace[361724568] 'read index received' (duration: 132.696002ms)","trace[361724568] 'applied index is now lower than readState.Index' (duration: 188.924213ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:33:47.391724Z","caller":"traceutil/trace.go:171","msg":"trace[1262427372] transaction","detail":"{read_only:false; response_revision:24823; number_of_response:1; }","duration":"334.074894ms","start":"2026-10-14T16:33:47.057633Z","end":"2026-10-14T16:33:47.391707Z","steps":["trace[1262427372] 'process raft request' (duration: 145.029389ms)","trace[1262427372] 'compare' (duration: 188.205277ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:33:47.391781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:33:47.057615Z","time spent":"334.134145ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:33:47.392156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.263899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:33:47.392201Z","caller":"traceutil/trace.go:171","msg":"trace[1433485080] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:24823; }","duration":"322.357381ms","start":"2026-10-14T16:33:47.069832Z","end":"2026-10-14T16:33:47.392189Z","steps":["trace[1433485080] 'agreement among raft nodes before linearized reading' (duration: 322.267589ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:33:47.392256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:33:47.069817Z","time spent":"322.425113ms","remote":"127.0.0.1:60982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":28,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"warn","ts":"2026-10-14T16:33:47.406031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.24757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-14T16:33:47.406138Z","caller":"traceutil/trace.go:171","msg":"trace[58266827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24824; }","duration":"281.394874ms","start":"2026-10-14T16:33:47.124717Z","end":"2026-10-14T16:33:47.406112Z","steps":["trace[58266827] 'agreement among raft nodes before linearized reading' (duration: 281.105487ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:33:47.966436Z","caller":"traceutil/trace.go:171","msg":"trace[1861793265] transaction","detail":"{read_only:false; response_revision:24825; number_of_response:1; }","duration":"555.385971ms","start":"2026-10-14T16:33:47.411028Z","end":"2026-10-14T16:33:47.966414Z","steps":["trace[1861793265] 'process raft request' (duration: 555.180307ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:33:47.966655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:33:47.411006Z","time spent":"555.577206ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:33:47.966721Z","caller":"traceutil/trace.go:171","msg":"trace[732673412] linearizableReadLoop","detail":"{readStateIndex:25649; appliedIndex:25647; }","duration":"467.527924ms","start":"2026-10-14T16:33:47.499162Z","end":"2026-10-14T16:33:47.96669Z","steps":["trace[732673412] 'read index received' (duration: 467.034212ms)","trace[732673412] 'applied index is now lower than readState.Index' (duration: 492.632µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:33:47.966946Z","caller":"traceutil/trace.go:171","msg":"trace[830586690] transaction","detail":"{read_only:false; response_revision:24826; number_of_response:1; }","duration":"555.893083ms","start":"2026-10-14T16:33:47.41104Z","end":"2026-10-14T16:33:47.966933Z","steps":["trace[830586690] 'process raft request' (duration: 555.33299ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:33:47.967093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:33:47.411015Z","time spent":"555.965784ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:33:47.967131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.878551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:33:47.967316Z","caller":"traceutil/trace.go:171","msg":"trace[2108602210] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24826; }","duration":"468.171837ms","start":"2026-10-14T16:33:47.499122Z","end":"2026-10-14T16:33:47.967294Z","steps":["trace[2108602210] 'agreement among raft nodes before linearized reading' (duration: 467.811389ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:33:47.967499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:33:47.499104Z","time spent":"468.376212ms","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true "} {"level":"info","ts":"2026-10-14T16:33:48.010464Z","caller":"traceutil/trace.go:171","msg":"trace[1745108755] transaction","detail":"{read_only:false; response_revision:24827; number_of_response:1; }","duration":"227.301452ms","start":"2026-10-14T16:33:47.78313Z","end":"2026-10-14T16:33:48.010431Z","steps":["trace[1745108755] 'process raft request' (duration: 227.112938ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:33:48.01068Z","caller":"traceutil/trace.go:171","msg":"trace[71366937] transaction","detail":"{read_only:false; response_revision:24828; number_of_response:1; }","duration":"155.599226ms","start":"2026-10-14T16:33:47.855051Z","end":"2026-10-14T16:33:48.01065Z","steps":["trace[71366937] 'process raft request' (duration: 155.3336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:33:48.010777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.126898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-10-14T16:33:48.010843Z","caller":"traceutil/trace.go:171","msg":"trace[442607037] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24828; }","duration":"456.24686ms","start":"2026-10-14T16:33:47.554577Z","end":"2026-10-14T16:33:48.010824Z","steps":["trace[442607037] 'agreement among raft nodes before linearized reading' (duration: 456.033196ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:33:48.01089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:33:47.55453Z","time spent":"456.338952ms","remote":"127.0.0.1:60462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-14T16:33:48.011033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.226858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-14T16:33:48.011079Z","caller":"traceutil/trace.go:171","msg":"trace[1068903615] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24828; }","duration":"251.31358ms","start":"2026-10-14T16:33:47.759753Z","end":"2026-10-14T16:33:48.011066Z","steps":["trace[1068903615] 'agreement among raft nodes before linearized reading' (duration: 251.129596ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:49.870877Z","caller":"traceutil/trace.go:171","msg":"trace[414591773] transaction","detail":"{read_only:false; response_revision:25390; number_of_response:1; }","duration":"213.962709ms","start":"2026-10-14T16:34:49.656883Z","end":"2026-10-14T16:34:49.870845Z","steps":["trace[414591773] 'process raft request' (duration: 213.289753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:50.233158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.078226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T16:34:50.233298Z","caller":"traceutil/trace.go:171","msg":"trace[670165456] transaction","detail":"{read_only:false; response_revision:25391; number_of_response:1; }","duration":"356.976247ms","start":"2026-10-14T16:34:49.876291Z","end":"2026-10-14T16:34:50.233267Z","steps":["trace[670165456] 'process raft request' (duration: 149.711906ms)","trace[670165456] 'compare' (duration: 206.979714ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:34:50.233355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:49.876254Z","time spent":"357.073599ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:34:50.80196Z","caller":"traceutil/trace.go:171","msg":"trace[524749818] transaction","detail":"{read_only:false; response_revision:25392; number_of_response:1; }","duration":"537.756754ms","start":"2026-10-14T16:34:50.264142Z","end":"2026-10-14T16:34:50.801898Z","steps":["trace[524749818] 'process raft request' (duration: 497.092186ms)","trace[524749818] 'compare' (duration: 40.407732ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:34:50.802123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:50.264119Z","time spent":"537.919868ms","remote":"127.0.0.1:50436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7681,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:34:51.225497Z","caller":"traceutil/trace.go:171","msg":"trace[2142371025] transaction","detail":"{read_only:false; response_revision:25393; number_of_response:1; }","duration":"876.683286ms","start":"2026-10-14T16:34:50.34878Z","end":"2026-10-14T16:34:51.225463Z","steps":["trace[2142371025] 'process raft request' (duration: 867.53322ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:51.22563Z","caller":"traceutil/trace.go:171","msg":"trace[2035980898] transaction","detail":"{read_only:false; response_revision:25394; number_of_response:1; }","duration":"874.019333ms","start":"2026-10-14T16:34:50.351574Z","end":"2026-10-14T16:34:51.225593Z","steps":["trace[2035980898] 'process raft request' (duration: 873.796637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:51.2257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:50.348746Z","time spent":"876.83923ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T16:34:51.225849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:50.351555Z","time spent":"874.169067ms","remote":"127.0.0.1:60588","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-10-14T16:34:51.226029Z","caller":"traceutil/trace.go:171","msg":"trace[118847726] transaction","detail":"{read_only:false; response_revision:25395; number_of_response:1; }","duration":"823.127714ms","start":"2026-10-14T16:34:50.402867Z","end":"2026-10-14T16:34:51.225995Z","steps":["trace[118847726] 'process raft request' (duration: 822.672743ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:51.226045Z","caller":"traceutil/trace.go:171","msg":"trace[81367084] linearizableReadLoop","detail":"{readStateIndex:26230; appliedIndex:26227; }","duration":"866.958946ms","start":"2026-10-14T16:34:50.359048Z","end":"2026-10-14T16:34:51.226007Z","steps":["trace[81367084] 'read index received' (duration: 402.275263ms)","trace[81367084] 'applied index is now lower than readState.Index' (duration: 464.682493ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T16:34:51.226165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:50.402851Z","time spent":"823.244218ms","remote":"127.0.0.1:60588","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-10-14T16:34:51.226323Z","caller":"traceutil/trace.go:171","msg":"trace[1803200543] transaction","detail":"{read_only:false; response_revision:25396; number_of_response:1; }","duration":"530.782459ms","start":"2026-10-14T16:34:50.695521Z","end":"2026-10-14T16:34:51.226304Z","steps":["trace[1803200543] 'process raft request' (duration: 530.323179ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:51.226347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.298344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-14T16:34:51.226367Z","caller":"traceutil/trace.go:171","msg":"trace[1704551311] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25396; }","duration":"867.346986ms","start":"2026-10-14T16:34:50.359014Z","end":"2026-10-14T16:34:51.226361Z","steps":["trace[1704551311] 'agreement among raft nodes before linearized reading' (duration: 867.239743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:51.226384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:50.359002Z","time spent":"867.378836ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-14T16:34:51.226414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:50.695494Z","time spent":"530.873402ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:34:51.240481Z","caller":"traceutil/trace.go:171","msg":"trace[1722048958] transaction","detail":"{read_only:false; response_revision:25397; number_of_response:1; }","duration":"477.372531ms","start":"2026-10-14T16:34:50.763077Z","end":"2026-10-14T16:34:51.240449Z","steps":["trace[1722048958] 'process raft request' (duration: 477.174127ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:51.240644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:50.763054Z","time spent":"477.505385ms","remote":"127.0.0.1:60588","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-10-14T16:34:51.240756Z","caller":"traceutil/trace.go:171","msg":"trace[1432787563] transaction","detail":"{read_only:false; response_revision:25398; number_of_response:1; }","duration":"464.96653ms","start":"2026-10-14T16:34:50.775764Z","end":"2026-10-14T16:34:51.24073Z","steps":["trace[1432787563] 'process raft request' (duration: 464.640172ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:51.240869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:50.77575Z","time spent":"465.060802ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:34:51.241153Z","caller":"traceutil/trace.go:171","msg":"trace[1792217303] transaction","detail":"{read_only:false; response_revision:25399; number_of_response:1; }","duration":"324.732787ms","start":"2026-10-14T16:34:50.916409Z","end":"2026-10-14T16:34:51.241141Z","steps":["trace[1792217303] 'process raft request' (duration: 324.243515ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:51.241229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:50.916383Z","time spent":"324.811129ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T16:34:51.241528Z","caller":"traceutil/trace.go:171","msg":"trace[1032346012] transaction","detail":"{read_only:false; response_revision:25400; number_of_response:1; }","duration":"278.205112ms","start":"2026-10-14T16:34:50.96331Z","end":"2026-10-14T16:34:51.241515Z","steps":["trace[1032346012] 'process raft request' (duration: 277.785582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:51.244262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.734357ms","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-10-14T16:34:51.24435Z","caller":"traceutil/trace.go:171","msg":"trace[1128151662] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25401; }","duration":"845.87562ms","start":"2026-10-14T16:34:50.398448Z","end":"2026-10-14T16:34:51.244324Z","steps":["trace[1128151662] 'agreement among raft nodes before linearized reading' (duration: 845.686155ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:51.244376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.05911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T16:34:51.244427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:50.398426Z","time spent":"845.987083ms","remote":"127.0.0.1:60588","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-10-14T16:34:51.244429Z","caller":"traceutil/trace.go:171","msg":"trace[603044637] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25401; }","duration":"149.172333ms","start":"2026-10-14T16:34:51.09524Z","end":"2026-10-14T16:34:51.244413Z","steps":["trace[603044637] 'agreement among raft nodes before linearized reading' (duration: 149.089861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:51.244618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.111358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T16:34:51.244627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.941525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T16:34:51.24466Z","caller":"traceutil/trace.go:171","msg":"trace[1161869890] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:25401; }","duration":"114.197449ms","start":"2026-10-14T16:34:51.130452Z","end":"2026-10-14T16:34:51.244649Z","steps":["trace[1161869890] 'agreement among raft nodes before linearized reading' (duration: 114.137458ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T16:34:51.244685Z","caller":"traceutil/trace.go:171","msg":"trace[742032197] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:25401; }","duration":"103.021596ms","start":"2026-10-14T16:34:51.141644Z","end":"2026-10-14T16:34:51.244666Z","steps":["trace[742032197] 'agreement among raft nodes before linearized reading' (duration: 102.919343ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:51.244707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.132267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T16:34:51.245105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.289389ms","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-10-14T16:34:51.245148Z","caller":"traceutil/trace.go:171","msg":"trace[758984871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25401; }","duration":"471.377921ms","start":"2026-10-14T16:34:50.77376Z","end":"2026-10-14T16:34:51.245138Z","steps":["trace[758984871] 'agreement among raft nodes before linearized reading' (duration: 471.244278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:51.245174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:50.773744Z","time spent":"471.423952ms","remote":"127.0.0.1:60588","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-10-14T16:34:51.245168Z","caller":"traceutil/trace.go:171","msg":"trace[301714488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25401; }","duration":"218.602628ms","start":"2026-10-14T16:34:51.026526Z","end":"2026-10-14T16:34:51.245129Z","steps":["trace[301714488] 'agreement among raft nodes before linearized reading' (duration: 218.132367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:51.245267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.548354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19029"} {"level":"info","ts":"2026-10-14T16:34:51.245309Z","caller":"traceutil/trace.go:171","msg":"trace[678230576] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:25401; }","duration":"434.622565ms","start":"2026-10-14T16:34:50.810676Z","end":"2026-10-14T16:34:51.245299Z","steps":["trace[678230576] 'agreement among raft nodes before linearized reading' (duration: 434.476951ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:51.245335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:50.810659Z","time spent":"434.668737ms","remote":"127.0.0.1:60478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":19051,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"warn","ts":"2026-10-14T16:34:51.245524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.933087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-14T16:34:51.245568Z","caller":"traceutil/trace.go:171","msg":"trace[942040563] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25401; }","duration":"611.013689ms","start":"2026-10-14T16:34:50.634544Z","end":"2026-10-14T16:34:51.245557Z","steps":["trace[942040563] 'agreement among raft nodes before linearized reading' (duration: 610.914287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T16:34:51.245594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T16:34:50.634522Z","time spent":"611.066281ms","remote":"127.0.0.1:60588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-14T16:35:12.308494Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22179} {"level":"info","ts":"2026-10-14T16:35:12.434146Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22179,"took":"119.802402ms","hash":4203380202,"current-db-size-bytes":58064896,"current-db-size":"58 MB","current-db-size-in-use-bytes":44560384,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-14T16:35:12.434202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4203380202,"revision":22179,"compact-revision":19973} {"level":"info","ts":"2026-10-14T16:35:19.703547Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067a7.wal"} {"level":"info","ts":"2026-10-14T16:40:12.321599Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25584} {"level":"info","ts":"2026-10-14T16:40:12.523208Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25584,"took":"195.125251ms","hash":208956824,"current-db-size-bytes":60198912,"current-db-size":"60 MB","current-db-size-in-use-bytes":35684352,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-14T16:40:12.523338Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":208956824,"revision":25584,"compact-revision":22179} {"level":"info","ts":"2026-10-14T16:41:37.694497Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3fd97315619643d2","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T16:41:37.701069Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-14T16:41:37.7012Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-14T16:45:12.33362Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28447} {"level":"info","ts":"2026-10-14T16:45:12.494062Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28447,"took":"155.870978ms","hash":461885337,"current-db-size-bytes":60198912,"current-db-size":"60 MB","current-db-size-in-use-bytes":30736384,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T16:45:12.494133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":461885337,"revision":28447,"compact-revision":25584} {"level":"info","ts":"2026-10-14T16:50:12.344349Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30670} {"level":"info","ts":"2026-10-14T16:50:12.473857Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30670,"took":"125.089296ms","hash":3010124746,"current-db-size-bytes":60198912,"current-db-size":"60 MB","current-db-size-in-use-bytes":31191040,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T16:50:12.473927Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3010124746,"revision":30670,"compact-revision":28447} {"level":"info","ts":"2026-10-14T16:54:19.557192Z","caller":"traceutil/trace.go:171","msg":"trace[1168332380] transaction","detail":"{read_only:false; response_revision:34791; number_of_response:1; }","duration":"120.217246ms","start":"2026-10-14T16:54:19.436922Z","end":"2026-10-14T16:54:19.55714Z","steps":["trace[1168332380] 'process raft request' (duration: 56.553539ms)","trace[1168332380] 'compare' (duration: 63.537504ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T16:55:12.35217Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32930} {"level":"info","ts":"2026-10-14T16:55:12.50914Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32930,"took":"153.714557ms","hash":2479829363,"current-db-size-bytes":61243392,"current-db-size":"61 MB","current-db-size-in-use-bytes":30986240,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-14T16:55:12.509191Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2479829363,"revision":32930,"compact-revision":30670} {"level":"info","ts":"2026-10-14T17:00:12.361093Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35225} {"level":"info","ts":"2026-10-14T17:00:12.499232Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35225,"took":"134.333303ms","hash":3668585152,"current-db-size-bytes":61243392,"current-db-size":"61 MB","current-db-size-in-use-bytes":33366016,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-14T17:00:12.499289Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3668585152,"revision":35225,"compact-revision":32930} {"level":"info","ts":"2026-10-14T17:02:59.959572Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3fd97315619643d2","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T17:02:59.96826Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-14T17:02:59.968396Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-14T17:05:12.367103Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37495} {"level":"info","ts":"2026-10-14T17:05:12.503535Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37495,"took":"131.941737ms","hash":1871276138,"current-db-size-bytes":61243392,"current-db-size":"61 MB","current-db-size-in-use-bytes":32210944,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T17:05:12.503599Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1871276138,"revision":37495,"compact-revision":35225}