{"level":"warn","ts":"2026-04-07T15:12:44.107236Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T15:12:44.107323Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-04-07T15:12:44.107397Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T15:12:44.10741Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-07T15:12:44.107428Z","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-04-07T15:12:44.108013Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-04-07T15:12:44.108082Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-04-07T15:12:44.114782Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.477715ms"} {"level":"info","ts":"2026-04-07T15:12:44.123909Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-04-07T15:12:44.12398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T15:12:44.124002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-04-07T15:12:44.124012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T15:12:44.124027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-04-07T15:12:44.124096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-04-07T15:12:44.131933Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T15:12:44.133608Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T15:12:44.134679Z","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-04-07T15:12:44.136436Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T15:12:44.138029Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T15:12:44.138062Z","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-04-07T15:12:44.138277Z","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-04-07T15:12:44.1383Z","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-04-07T15:12:44.145861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-04-07T15:12:44.145988Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-07T15:12:44.146181Z","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-04-07T15:12:44.146242Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-07T15:12:44.146265Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-07T15:12:44.146406Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T15:12:44.146443Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T15:12:44.224535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T15:12:44.224642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T15:12:44.224707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-04-07T15:12:44.224764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-04-07T15:12:44.224804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-07T15:12:44.224856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-04-07T15:12:44.224899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-07T15:12:44.22579Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:12:44.225907Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T15:12:44.226059Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:12:44.226139Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:12:44.226688Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:12:44.226841Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:12:44.227758Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:12:44.229077Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-04-07T15:12:44.229583Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T15:12:44.239756Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T15:12:44.239772Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T15:13:01.264793Z","caller":"traceutil/trace.go:171","msg":"trace[44880037] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"139.491547ms","start":"2026-04-07T15:13:01.125277Z","end":"2026-04-07T15:13:01.264769Z","steps":["trace[44880037] 'process raft request' (duration: 139.016873ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:01.621833Z","caller":"traceutil/trace.go:171","msg":"trace[339489386] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"201.993124ms","start":"2026-04-07T15:13:01.419812Z","end":"2026-04-07T15:13:01.621805Z","steps":["trace[339489386] 'read index received' (duration: 201.840322ms)","trace[339489386] 'applied index is now lower than readState.Index' (duration: 151.852µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:13:01.622003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.175715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:13:01.622036Z","caller":"traceutil/trace.go:171","msg":"trace[1078341577] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"344.167986ms","start":"2026-04-07T15:13:01.277826Z","end":"2026-04-07T15:13:01.621994Z","steps":["trace[1078341577] 'process raft request' (duration: 343.842554ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:01.622123Z","caller":"traceutil/trace.go:171","msg":"trace[414137442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"202.391557ms","start":"2026-04-07T15:13:01.419708Z","end":"2026-04-07T15:13:01.622099Z","steps":["trace[414137442] 'agreement among raft nodes before linearized reading' (duration: 202.205386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:01.622854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:01.277814Z","time spent":"344.419999ms","remote":"127.0.0.1:55910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:13:02.16667Z","caller":"traceutil/trace.go:171","msg":"trace[2114974304] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"533.849387ms","start":"2026-04-07T15:13:01.632776Z","end":"2026-04-07T15:13:02.166626Z","steps":["trace[2114974304] 'process raft request' (duration: 474.566087ms)","trace[2114974304] 'compare' (duration: 59.072288ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:13:02.166723Z","caller":"traceutil/trace.go:171","msg":"trace[1700405369] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"515.818369ms","start":"2026-04-07T15:13:01.650872Z","end":"2026-04-07T15:13:02.16669Z","steps":["trace[1700405369] 'read index received' (duration: 456.425578ms)","trace[1700405369] 'applied index is now lower than readState.Index' (duration: 59.389351ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:13:02.166914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:01.632764Z","time spent":"534.020589ms","remote":"127.0.0.1:55910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:13:02.18147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.766787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-07T15:13:02.181512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.610676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-04-07T15:13:02.181549Z","caller":"traceutil/trace.go:171","msg":"trace[1821526295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"402.918459ms","start":"2026-04-07T15:13:01.778612Z","end":"2026-04-07T15:13:02.18153Z","steps":["trace[1821526295] 'agreement among raft nodes before linearized reading' (duration: 402.800878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:02.181584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:01.778581Z","time spent":"402.996149ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T15:13:02.181554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.635318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T15:13:02.181591Z","caller":"traceutil/trace.go:171","msg":"trace[1273123334] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:413; }","duration":"530.740657ms","start":"2026-04-07T15:13:01.650823Z","end":"2026-04-07T15:13:02.181564Z","steps":["trace[1273123334] 'agreement among raft nodes before linearized reading' (duration: 516.01291ms)","trace[1273123334] 'range keys from in-memory index tree' (duration: 14.573986ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:13:02.181657Z","caller":"traceutil/trace.go:171","msg":"trace[1233269251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:413; }","duration":"400.800159ms","start":"2026-04-07T15:13:01.780821Z","end":"2026-04-07T15:13:02.181621Z","steps":["trace[1233269251] 'agreement among raft nodes before linearized reading' (duration: 400.522956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:02.18172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:01.780797Z","time spent":"400.90926ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T15:13:02.181772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:01.650805Z","time spent":"530.828498ms","remote":"127.0.0.1:55904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4392,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-07T15:13:02.181807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.09882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T15:13:02.181863Z","caller":"traceutil/trace.go:171","msg":"trace[1271260797] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:413; }","duration":"125.200472ms","start":"2026-04-07T15:13:02.056646Z","end":"2026-04-07T15:13:02.181846Z","steps":["trace[1271260797] 'agreement among raft nodes before linearized reading' (duration: 125.107121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:22.398629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.091046ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T15:13:22.39875Z","caller":"traceutil/trace.go:171","msg":"trace[864693976] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"114.03239ms","start":"2026-04-07T15:13:22.284691Z","end":"2026-04-07T15:13:22.398723Z","steps":["trace[864693976] 'read index received' (duration: 42.45µs)","trace[864693976] 'applied index is now lower than readState.Index' (duration: 113.98857ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:13:22.39893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.230272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T15:13:22.398965Z","caller":"traceutil/trace.go:171","msg":"trace[179460697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:617; }","duration":"114.309652ms","start":"2026-04-07T15:13:22.284647Z","end":"2026-04-07T15:13:22.398956Z","steps":["trace[179460697] 'agreement among raft nodes before linearized reading' (duration: 114.160001ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:22.516535Z","caller":"traceutil/trace.go:171","msg":"trace[1803653836] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"117.727279ms","start":"2026-04-07T15:13:22.398783Z","end":"2026-04-07T15:13:22.51651Z","steps":["trace[1803653836] 'read index received' (duration: 116.865102ms)","trace[1803653836] 'applied index is now lower than readState.Index' (duration: 861.307µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:13:22.51659Z","caller":"traceutil/trace.go:171","msg":"trace[997248672] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"218.180883ms","start":"2026-04-07T15:13:22.298367Z","end":"2026-04-07T15:13:22.516548Z","steps":["trace[997248672] 'process raft request' (duration: 217.581248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:22.516756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.28049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T15:13:22.516792Z","caller":"traceutil/trace.go:171","msg":"trace[272500823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"225.35522ms","start":"2026-04-07T15:13:22.291426Z","end":"2026-04-07T15:13:22.516781Z","steps":["trace[272500823] 'agreement among raft nodes before linearized reading' (duration: 225.214849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:22.516831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.327741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T15:13:22.516905Z","caller":"traceutil/trace.go:171","msg":"trace[324854117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"224.448992ms","start":"2026-04-07T15:13:22.292438Z","end":"2026-04-07T15:13:22.516887Z","steps":["trace[324854117] 'agreement among raft nodes before linearized reading' (duration: 224.117629ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:22.523393Z","caller":"traceutil/trace.go:171","msg":"trace[555660010] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"120.759452ms","start":"2026-04-07T15:13:22.402612Z","end":"2026-04-07T15:13:22.523372Z","steps":["trace[555660010] 'process raft request' (duration: 119.700404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:22.5246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.958074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:13:22.524663Z","caller":"traceutil/trace.go:171","msg":"trace[1977934293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"107.557221ms","start":"2026-04-07T15:13:22.417089Z","end":"2026-04-07T15:13:22.524646Z","steps":["trace[1977934293] 'agreement among raft nodes before linearized reading' (duration: 105.898227ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:22.751497Z","caller":"traceutil/trace.go:171","msg":"trace[1603499493] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"228.280563ms","start":"2026-04-07T15:13:22.523189Z","end":"2026-04-07T15:13:22.75147Z","steps":["trace[1603499493] 'process raft request' (duration: 202.746632ms)","trace[1603499493] 'compare' (duration: 25.41156ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:13:22.771862Z","caller":"traceutil/trace.go:171","msg":"trace[2134151316] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"205.948678ms","start":"2026-04-07T15:13:22.565897Z","end":"2026-04-07T15:13:22.771846Z","steps":["trace[2134151316] 'process raft request' (duration: 205.917268ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:22.771972Z","caller":"traceutil/trace.go:171","msg":"trace[1558262287] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"248.303139ms","start":"2026-04-07T15:13:22.523643Z","end":"2026-04-07T15:13:22.771946Z","steps":["trace[1558262287] 'process raft request' (duration: 248.087608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:23.534049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.639649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8t5cg\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-07T15:13:23.534161Z","caller":"traceutil/trace.go:171","msg":"trace[1246021236] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8t5cg; range_end:; response_count:1; response_revision:622; }","duration":"670.78309ms","start":"2026-04-07T15:13:22.863347Z","end":"2026-04-07T15:13:23.53413Z","steps":["trace[1246021236] 'range keys from in-memory index tree' (duration: 670.524307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:23.534219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:22.863334Z","time spent":"670.87396ms","remote":"127.0.0.1:55910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-8t5cg\" "} {"level":"warn","ts":"2026-04-07T15:13:23.534641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.346891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:13:23.534693Z","caller":"traceutil/trace.go:171","msg":"trace[1923791237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"357.424652ms","start":"2026-04-07T15:13:23.177248Z","end":"2026-04-07T15:13:23.534672Z","steps":["trace[1923791237] 'range keys from in-memory index tree' (duration: 357.26661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:23.534728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:23.177206Z","time spent":"357.514372ms","remote":"127.0.0.1:55734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T15:13:23.538956Z","caller":"traceutil/trace.go:171","msg":"trace[88304667] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"157.793315ms","start":"2026-04-07T15:13:23.381091Z","end":"2026-04-07T15:13:23.538885Z","steps":["trace[88304667] 'read index received' (duration: 148.307941ms)","trace[88304667] 'applied index is now lower than readState.Index' (duration: 9.483974ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:13:23.539184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.078477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T15:13:23.539222Z","caller":"traceutil/trace.go:171","msg":"trace[1439913571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"158.173997ms","start":"2026-04-07T15:13:23.381035Z","end":"2026-04-07T15:13:23.539209Z","steps":["trace[1439913571] 'agreement among raft nodes before linearized reading' (duration: 158.006066ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:23.539385Z","caller":"traceutil/trace.go:171","msg":"trace[1450929789] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"358.191829ms","start":"2026-04-07T15:13:23.181183Z","end":"2026-04-07T15:13:23.539375Z","steps":["trace[1450929789] 'process raft request' (duration: 353.765374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:23.539506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:13:23.181156Z","time spent":"358.253749ms","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:13:39.233023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.159276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fbc7k\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-07T15:13:39.233168Z","caller":"traceutil/trace.go:171","msg":"trace[273932041] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fbc7k; range_end:; response_count:1; response_revision:787; }","duration":"273.448958ms","start":"2026-04-07T15:13:38.959689Z","end":"2026-04-07T15:13:39.233138Z","steps":["trace[273932041] 'range keys from in-memory index tree' (duration: 272.865713ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:08.28373Z","caller":"traceutil/trace.go:171","msg":"trace[749935152] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"148.895347ms","start":"2026-04-07T15:14:08.134807Z","end":"2026-04-07T15:14:08.283703Z","steps":["trace[749935152] 'process raft request' (duration: 148.721712ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:24.979751Z","caller":"traceutil/trace.go:171","msg":"trace[202443597] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"131.007801ms","start":"2026-04-07T15:14:24.84871Z","end":"2026-04-07T15:14:24.979718Z","steps":["trace[202443597] 'process raft request' (duration: 130.05416ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:58.368021Z","caller":"traceutil/trace.go:171","msg":"trace[2053439442] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"158.419467ms","start":"2026-04-07T15:14:58.209574Z","end":"2026-04-07T15:14:58.367993Z","steps":["trace[2053439442] 'process raft request' (duration: 158.236114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:58.725121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.243131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:14:58.72531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.538474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ab9c615c-b55f-4abd-9896-f5b3a86b8c97\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-07T15:14:58.725411Z","caller":"traceutil/trace.go:171","msg":"trace[906998133] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ab9c615c-b55f-4abd-9896-f5b3a86b8c97; range_end:; response_count:1; response_revision:1863; }","duration":"326.761838ms","start":"2026-04-07T15:14:58.398606Z","end":"2026-04-07T15:14:58.725368Z","steps":["trace[906998133] 'range keys from in-memory index tree' (duration: 326.390631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:58.725512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:58.398587Z","time spent":"326.90963ms","remote":"127.0.0.1:55910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ab9c615c-b55f-4abd-9896-f5b3a86b8c97\" "} {"level":"info","ts":"2026-04-07T15:14:58.725223Z","caller":"traceutil/trace.go:171","msg":"trace[1588693429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1863; }","duration":"304.396624ms","start":"2026-04-07T15:14:58.420801Z","end":"2026-04-07T15:14:58.725197Z","steps":["trace[1588693429] 'range keys from in-memory index tree' (duration: 304.13702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:58.725631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:58.420777Z","time spent":"304.833941ms","remote":"127.0.0.1:55734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T15:14:58.72582Z","caller":"traceutil/trace.go:171","msg":"trace[1923774789] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"241.323491ms","start":"2026-04-07T15:14:58.484456Z","end":"2026-04-07T15:14:58.72578Z","steps":["trace[1923774789] 'process raft request' (duration: 150.961351ms)","trace[1923774789] 'compare' (duration: 89.663018ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:15:17.649674Z","caller":"traceutil/trace.go:171","msg":"trace[1128663528] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"176.111768ms","start":"2026-04-07T15:15:17.473471Z","end":"2026-04-07T15:15:17.649582Z","steps":["trace[1128663528] 'process raft request' (duration: 176.057447ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:15:17.649939Z","caller":"traceutil/trace.go:171","msg":"trace[655905527] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"177.326556ms","start":"2026-04-07T15:15:17.47257Z","end":"2026-04-07T15:15:17.649897Z","steps":["trace[655905527] 'process raft request' (duration: 130.108649ms)","trace[655905527] 'compare' (duration: 46.71702ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:15:20.739714Z","caller":"traceutil/trace.go:171","msg":"trace[439482563] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"240.88453ms","start":"2026-04-07T15:15:20.498804Z","end":"2026-04-07T15:15:20.739689Z","steps":["trace[439482563] 'process raft request' (duration: 240.753098ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:15:54.190214Z","caller":"traceutil/trace.go:171","msg":"trace[639458023] linearizableReadLoop","detail":"{readStateIndex:2304; appliedIndex:2303; }","duration":"362.396817ms","start":"2026-04-07T15:15:53.827789Z","end":"2026-04-07T15:15:54.190186Z","steps":["trace[639458023] 'read index received' (duration: 362.204394ms)","trace[639458023] 'applied index is now lower than readState.Index' (duration: 191.373µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:15:54.190273Z","caller":"traceutil/trace.go:171","msg":"trace[1428910244] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"365.534015ms","start":"2026-04-07T15:15:53.824703Z","end":"2026-04-07T15:15:54.190237Z","steps":["trace[1428910244] 'process raft request' (duration: 365.298913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:15:54.19045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:15:53.824681Z","time spent":"365.673177ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:15:54.190461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.63935ms","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-04-07T15:15:54.1905Z","caller":"traceutil/trace.go:171","msg":"trace[886003556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2215; }","duration":"362.72405ms","start":"2026-04-07T15:15:53.827766Z","end":"2026-04-07T15:15:54.19049Z","steps":["trace[886003556] 'agreement among raft nodes before linearized reading' (duration: 362.561108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:15:54.190538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:15:53.827755Z","time spent":"362.774231ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T15:15:54.267812Z","caller":"traceutil/trace.go:171","msg":"trace[1557009189] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"279.53808ms","start":"2026-04-07T15:15:53.988251Z","end":"2026-04-07T15:15:54.267789Z","steps":["trace[1557009189] 'process raft request' (duration: 279.386469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:15:54.267956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.021946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:15:54.268037Z","caller":"traceutil/trace.go:171","msg":"trace[794902798] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2216; }","duration":"227.155267ms","start":"2026-04-07T15:15:54.040863Z","end":"2026-04-07T15:15:54.268018Z","steps":["trace[794902798] 'agreement among raft nodes before linearized reading' (duration: 226.950735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:15:54.268562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.280694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-04-07T15:15:54.268612Z","caller":"traceutil/trace.go:171","msg":"trace[1951750358] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2216; }","duration":"216.351206ms","start":"2026-04-07T15:15:54.052248Z","end":"2026-04-07T15:15:54.268599Z","steps":["trace[1951750358] 'agreement among raft nodes before linearized reading' (duration: 215.602656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:15:54.576275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.307769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:15:54.57645Z","caller":"traceutil/trace.go:171","msg":"trace[2122739816] linearizableReadLoop","detail":"{readStateIndex:2306; appliedIndex:2305; }","duration":"297.105386ms","start":"2026-04-07T15:15:54.279321Z","end":"2026-04-07T15:15:54.576426Z","steps":["trace[2122739816] 'read index received' (duration: 122.259ms)","trace[2122739816] 'applied index is now lower than readState.Index' (duration: 174.844476ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:15:54.57654Z","caller":"traceutil/trace.go:171","msg":"trace[199214660] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"380.811212ms","start":"2026-04-07T15:15:54.195655Z","end":"2026-04-07T15:15:54.576466Z","steps":["trace[199214660] 'process raft request' (duration: 206.013667ms)","trace[199214660] 'compare' (duration: 174.168158ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:15:54.576659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.323019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:15:54.576668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:15:54.195638Z","time spent":"380.972215ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:15:54.576729Z","caller":"traceutil/trace.go:171","msg":"trace[1536861871] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2217; }","duration":"297.427701ms","start":"2026-04-07T15:15:54.279285Z","end":"2026-04-07T15:15:54.576713Z","steps":["trace[1536861871] 'agreement among raft nodes before linearized reading' (duration: 297.292819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:15:54.577022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.827903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T15:15:54.577092Z","caller":"traceutil/trace.go:171","msg":"trace[1479702969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2217; }","duration":"269.927313ms","start":"2026-04-07T15:15:54.307149Z","end":"2026-04-07T15:15:54.577076Z","steps":["trace[1479702969] 'agreement among raft nodes before linearized reading' (duration: 269.602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:15:54.577211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.84977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:15:54.577362Z","caller":"traceutil/trace.go:171","msg":"trace[124982781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2217; }","duration":"159.020981ms","start":"2026-04-07T15:15:54.418298Z","end":"2026-04-07T15:15:54.577319Z","steps":["trace[124982781] 'agreement among raft nodes before linearized reading' (duration: 158.536516ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:15:54.791008Z","caller":"traceutil/trace.go:171","msg":"trace[1679234184] linearizableReadLoop","detail":"{readStateIndex:2307; appliedIndex:2306; }","duration":"204.039954ms","start":"2026-04-07T15:15:54.586942Z","end":"2026-04-07T15:15:54.790982Z","steps":["trace[1679234184] 'read index received' (duration: 187.128436ms)","trace[1679234184] 'applied index is now lower than readState.Index' (duration: 16.910578ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:15:54.791189Z","caller":"traceutil/trace.go:171","msg":"trace[139945615] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"208.988865ms","start":"2026-04-07T15:15:54.582104Z","end":"2026-04-07T15:15:54.791093Z","steps":["trace[139945615] 'process raft request' (duration: 192.019087ms)","trace[139945615] 'compare' (duration: 16.734905ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:15:54.79148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.402918ms","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-04-07T15:15:54.791579Z","caller":"traceutil/trace.go:171","msg":"trace[522726031] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2218; }","duration":"204.611331ms","start":"2026-04-07T15:15:54.586931Z","end":"2026-04-07T15:15:54.791543Z","steps":["trace[522726031] 'agreement among raft nodes before linearized reading' (duration: 204.145865ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:16:39.306241Z","caller":"traceutil/trace.go:171","msg":"trace[30250579] transaction","detail":"{read_only:false; response_revision:2565; number_of_response:1; }","duration":"112.28929ms","start":"2026-04-07T15:16:39.193928Z","end":"2026-04-07T15:16:39.306217Z","steps":["trace[30250579] 'process raft request' (duration: 105.728669ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:16:39.987001Z","caller":"traceutil/trace.go:171","msg":"trace[812531717] linearizableReadLoop","detail":"{readStateIndex:2665; appliedIndex:2664; }","duration":"151.742431ms","start":"2026-04-07T15:16:39.835233Z","end":"2026-04-07T15:16:39.986976Z","steps":["trace[812531717] 'read index received' (duration: 151.508069ms)","trace[812531717] 'applied index is now lower than readState.Index' (duration: 233.322µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:16:39.987059Z","caller":"traceutil/trace.go:171","msg":"trace[548515324] transaction","detail":"{read_only:false; response_revision:2566; number_of_response:1; }","duration":"246.816957ms","start":"2026-04-07T15:16:39.740222Z","end":"2026-04-07T15:16:39.987039Z","steps":["trace[548515324] 'process raft request' (duration: 246.603164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:16:39.987254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.006704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-07T15:16:39.987291Z","caller":"traceutil/trace.go:171","msg":"trace[451751634] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2566; }","duration":"152.079674ms","start":"2026-04-07T15:16:39.8352Z","end":"2026-04-07T15:16:39.98728Z","steps":["trace[451751634] 'agreement among raft nodes before linearized reading' (duration: 151.895762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:17:13.628197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.000299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:17:13.628362Z","caller":"traceutil/trace.go:171","msg":"trace[1281731792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2753; }","duration":"209.224161ms","start":"2026-04-07T15:17:13.419123Z","end":"2026-04-07T15:17:13.628347Z","steps":["trace[1281731792] 'range keys from in-memory index tree' (duration: 208.879718ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:17:13.628815Z","caller":"traceutil/trace.go:171","msg":"trace[918436477] linearizableReadLoop","detail":"{readStateIndex:2861; appliedIndex:2860; }","duration":"188.136388ms","start":"2026-04-07T15:17:13.440667Z","end":"2026-04-07T15:17:13.628803Z","steps":["trace[918436477] 'read index received' (duration: 107.995053ms)","trace[918436477] 'applied index is now lower than readState.Index' (duration: 80.140575ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:17:13.628915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.24727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:17:13.628966Z","caller":"traceutil/trace.go:171","msg":"trace[96857191] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:2754; }","duration":"188.343181ms","start":"2026-04-07T15:17:13.440613Z","end":"2026-04-07T15:17:13.628957Z","steps":["trace[96857191] 'agreement among raft nodes before linearized reading' (duration: 188.24428ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:17:13.629151Z","caller":"traceutil/trace.go:171","msg":"trace[720984245] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"200.791676ms","start":"2026-04-07T15:17:13.428327Z","end":"2026-04-07T15:17:13.629119Z","steps":["trace[720984245] 'process raft request' (duration: 120.417539ms)","trace[720984245] 'compare' (duration: 79.972563ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:20:34.00966Z","caller":"traceutil/trace.go:171","msg":"trace[1741058966] transaction","detail":"{read_only:false; response_revision:3974; number_of_response:1; }","duration":"111.852202ms","start":"2026-04-07T15:20:33.897781Z","end":"2026-04-07T15:20:34.009633Z","steps":["trace[1741058966] 'process raft request' (duration: 111.708639ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:58.810204Z","caller":"traceutil/trace.go:171","msg":"trace[699604122] transaction","detail":"{read_only:false; response_revision:4448; number_of_response:1; }","duration":"112.276917ms","start":"2026-04-07T15:20:58.6979Z","end":"2026-04-07T15:20:58.810177Z","steps":["trace[699604122] 'process raft request' (duration: 106.283008ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:01.310956Z","caller":"traceutil/trace.go:171","msg":"trace[1216973610] transaction","detail":"{read_only:false; response_revision:4548; number_of_response:1; }","duration":"106.492294ms","start":"2026-04-07T15:21:01.204422Z","end":"2026-04-07T15:21:01.310915Z","steps":["trace[1216973610] 'process raft request' (duration: 106.099987ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:04.444271Z","caller":"traceutil/trace.go:171","msg":"trace[513285629] linearizableReadLoop","detail":"{readStateIndex:4778; appliedIndex:4777; }","duration":"111.496455ms","start":"2026-04-07T15:21:04.33274Z","end":"2026-04-07T15:21:04.444237Z","steps":["trace[513285629] 'read index received' (duration: 111.216079ms)","trace[513285629] 'applied index is now lower than readState.Index' (duration: 279.126µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:04.444393Z","caller":"traceutil/trace.go:171","msg":"trace[1100815001] transaction","detail":"{read_only:false; response_revision:4599; number_of_response:1; }","duration":"169.034273ms","start":"2026-04-07T15:21:04.275348Z","end":"2026-04-07T15:21:04.444382Z","steps":["trace[1100815001] 'process raft request' (duration: 168.712166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:04.461951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.204862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-07T15:21:04.462293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.389745ms","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-04-07T15:21:04.462002Z","caller":"traceutil/trace.go:171","msg":"trace[1207112086] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4599; }","duration":"129.301094ms","start":"2026-04-07T15:21:04.332688Z","end":"2026-04-07T15:21:04.46199Z","steps":["trace[1207112086] 'agreement among raft nodes before linearized reading' (duration: 111.958794ms)","trace[1207112086] 'range keys from in-memory index tree' (duration: 17.231208ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:04.46236Z","caller":"traceutil/trace.go:171","msg":"trace[1842015345] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4599; }","duration":"106.507348ms","start":"2026-04-07T15:21:04.355834Z","end":"2026-04-07T15:21:04.462342Z","steps":["trace[1842015345] 'agreement among raft nodes before linearized reading' (duration: 88.834071ms)","trace[1842015345] 'range keys from in-memory index tree' (duration: 17.513624ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:04.633549Z","caller":"traceutil/trace.go:171","msg":"trace[1695326572] transaction","detail":"{read_only:false; response_revision:4603; number_of_response:1; }","duration":"159.407863ms","start":"2026-04-07T15:21:04.474107Z","end":"2026-04-07T15:21:04.633515Z","steps":["trace[1695326572] 'process raft request' (duration: 158.72709ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:04.762365Z","caller":"traceutil/trace.go:171","msg":"trace[1075858638] transaction","detail":"{read_only:false; response_revision:4606; number_of_response:1; }","duration":"123.182964ms","start":"2026-04-07T15:21:04.639159Z","end":"2026-04-07T15:21:04.762342Z","steps":["trace[1075858638] 'process raft request' (duration: 120.994021ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:04.776699Z","caller":"traceutil/trace.go:171","msg":"trace[1609191171] transaction","detail":"{read_only:false; response_revision:4607; number_of_response:1; }","duration":"134.658559ms","start":"2026-04-07T15:21:04.642018Z","end":"2026-04-07T15:21:04.776677Z","steps":["trace[1609191171] 'process raft request' (duration: 134.478965ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:04.777015Z","caller":"traceutil/trace.go:171","msg":"trace[872843379] transaction","detail":"{read_only:false; response_revision:4608; number_of_response:1; }","duration":"133.7549ms","start":"2026-04-07T15:21:04.643243Z","end":"2026-04-07T15:21:04.776998Z","steps":["trace[872843379] 'process raft request' (duration: 133.376603ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:04.777282Z","caller":"traceutil/trace.go:171","msg":"trace[1909753406] transaction","detail":"{read_only:false; response_revision:4609; number_of_response:1; }","duration":"133.330642ms","start":"2026-04-07T15:21:04.643931Z","end":"2026-04-07T15:21:04.777262Z","steps":["trace[1909753406] 'process raft request' (duration: 132.987225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:07.467649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.588535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:21:07.467755Z","caller":"traceutil/trace.go:171","msg":"trace[1644030579] linearizableReadLoop","detail":"{readStateIndex:4834; appliedIndex:4833; }","duration":"156.493031ms","start":"2026-04-07T15:21:07.311246Z","end":"2026-04-07T15:21:07.467739Z","steps":["trace[1644030579] 'read index received' (duration: 37.733722ms)","trace[1644030579] 'applied index is now lower than readState.Index' (duration: 118.757769ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:07.467789Z","caller":"traceutil/trace.go:171","msg":"trace[1684566817] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"190.161767ms","start":"2026-04-07T15:21:07.277597Z","end":"2026-04-07T15:21:07.467759Z","steps":["trace[1684566817] 'process raft request' (duration: 71.403308ms)","trace[1684566817] 'compare' (duration: 117.955334ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:21:07.467956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.709926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:21:07.467996Z","caller":"traceutil/trace.go:171","msg":"trace[590925956] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:4652; }","duration":"156.787337ms","start":"2026-04-07T15:21:07.311199Z","end":"2026-04-07T15:21:07.467986Z","steps":["trace[590925956] 'agreement among raft nodes before linearized reading' (duration: 156.636944ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:07.6553Z","caller":"traceutil/trace.go:171","msg":"trace[1617024100] transaction","detail":"{read_only:false; response_revision:4656; number_of_response:1; }","duration":"102.91163ms","start":"2026-04-07T15:21:07.552363Z","end":"2026-04-07T15:21:07.655274Z","steps":["trace[1617024100] 'process raft request' (duration: 102.769668ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:07.891674Z","caller":"traceutil/trace.go:171","msg":"trace[1798086156] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"231.693943ms","start":"2026-04-07T15:21:07.659958Z","end":"2026-04-07T15:21:07.891652Z","steps":["trace[1798086156] 'process raft request' (duration: 216.702962ms)","trace[1798086156] 'compare' (duration: 14.866269ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:07.893109Z","caller":"traceutil/trace.go:171","msg":"trace[1231386281] linearizableReadLoop","detail":"{readStateIndex:4842; appliedIndex:4838; }","duration":"229.150214ms","start":"2026-04-07T15:21:07.663944Z","end":"2026-04-07T15:21:07.893094Z","steps":["trace[1231386281] 'read index received' (duration: 212.708524ms)","trace[1231386281] 'applied index is now lower than readState.Index' (duration: 16.4407ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:07.893198Z","caller":"traceutil/trace.go:171","msg":"trace[79773581] transaction","detail":"{read_only:false; response_revision:4662; number_of_response:1; }","duration":"228.93719ms","start":"2026-04-07T15:21:07.664252Z","end":"2026-04-07T15:21:07.89319Z","steps":["trace[79773581] 'process raft request' (duration: 228.710125ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:07.893408Z","caller":"traceutil/trace.go:171","msg":"trace[1346098013] transaction","detail":"{read_only:false; response_revision:4663; number_of_response:1; }","duration":"229.095173ms","start":"2026-04-07T15:21:07.664299Z","end":"2026-04-07T15:21:07.893395Z","steps":["trace[1346098013] 'process raft request' (duration: 228.699286ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:07.893439Z","caller":"traceutil/trace.go:171","msg":"trace[2004003486] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"231.254725ms","start":"2026-04-07T15:21:07.662165Z","end":"2026-04-07T15:21:07.89342Z","steps":["trace[2004003486] 'process raft request' (duration: 230.664724ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:07.893468Z","caller":"traceutil/trace.go:171","msg":"trace[306663610] transaction","detail":"{read_only:false; response_revision:4658; number_of_response:1; }","duration":"232.421858ms","start":"2026-04-07T15:21:07.66099Z","end":"2026-04-07T15:21:07.893412Z","steps":["trace[306663610] 'process raft request' (duration: 231.769485ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:07.893568Z","caller":"traceutil/trace.go:171","msg":"trace[607229310] transaction","detail":"{read_only:false; response_revision:4660; number_of_response:1; }","duration":"231.260645ms","start":"2026-04-07T15:21:07.662277Z","end":"2026-04-07T15:21:07.893537Z","steps":["trace[607229310] 'process raft request' (duration: 230.595982ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:07.893451Z","caller":"traceutil/trace.go:171","msg":"trace[2141510298] transaction","detail":"{read_only:false; response_revision:4661; number_of_response:1; }","duration":"229.214285ms","start":"2026-04-07T15:21:07.664117Z","end":"2026-04-07T15:21:07.893331Z","steps":["trace[2141510298] 'process raft request' (duration: 228.785287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:07.893674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.713454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"warn","ts":"2026-04-07T15:21:07.894738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.970297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193165"} {"level":"info","ts":"2026-04-07T15:21:07.894798Z","caller":"traceutil/trace.go:171","msg":"trace[1117431511] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4665; }","duration":"200.065648ms","start":"2026-04-07T15:21:07.694716Z","end":"2026-04-07T15:21:07.894782Z","steps":["trace[1117431511] 'agreement among raft nodes before linearized reading' (duration: 199.10254ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:07.89493Z","caller":"traceutil/trace.go:171","msg":"trace[1976622023] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4665; }","duration":"230.991389ms","start":"2026-04-07T15:21:07.663917Z","end":"2026-04-07T15:21:07.894909Z","steps":["trace[1976622023] 'agreement among raft nodes before linearized reading' (duration: 229.624663ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:07.89372Z","caller":"traceutil/trace.go:171","msg":"trace[220086354] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"229.409159ms","start":"2026-04-07T15:21:07.664301Z","end":"2026-04-07T15:21:07.893711Z","steps":["trace[220086354] 'process raft request' (duration: 228.723456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:07.893792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.713115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-hgdv6\" ","response":"range_response_count:1 size:5151"} {"level":"info","ts":"2026-04-07T15:21:07.895338Z","caller":"traceutil/trace.go:171","msg":"trace[541360370] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-hgdv6; range_end:; response_count:1; response_revision:4665; }","duration":"231.286425ms","start":"2026-04-07T15:21:07.664038Z","end":"2026-04-07T15:21:07.895325Z","steps":["trace[541360370] 'agreement among raft nodes before linearized reading' (duration: 229.624284ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:07.89378Z","caller":"traceutil/trace.go:171","msg":"trace[1693267452] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"222.985544ms","start":"2026-04-07T15:21:07.670772Z","end":"2026-04-07T15:21:07.893758Z","steps":["trace[1693267452] 'process raft request' (duration: 222.278511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:07.893937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.564375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T15:21:07.896533Z","caller":"traceutil/trace.go:171","msg":"trace[1906671892] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4665; }","duration":"173.184276ms","start":"2026-04-07T15:21:07.723328Z","end":"2026-04-07T15:21:07.896513Z","steps":["trace[1906671892] 'agreement among raft nodes before linearized reading' (duration: 170.468463ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:08.780393Z","caller":"traceutil/trace.go:171","msg":"trace[1846234592] transaction","detail":"{read_only:false; response_revision:4689; number_of_response:1; }","duration":"133.931354ms","start":"2026-04-07T15:21:08.646435Z","end":"2026-04-07T15:21:08.780366Z","steps":["trace[1846234592] 'process raft request' (duration: 133.71226ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:09.073348Z","caller":"traceutil/trace.go:171","msg":"trace[567352985] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"279.818492ms","start":"2026-04-07T15:21:08.793494Z","end":"2026-04-07T15:21:09.073313Z","steps":["trace[567352985] 'process raft request' (duration: 240.209286ms)","trace[567352985] 'compare' (duration: 38.827621ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:09.072826Z","caller":"traceutil/trace.go:171","msg":"trace[35192116] linearizableReadLoop","detail":"{readStateIndex:4874; appliedIndex:4873; }","duration":"267.724657ms","start":"2026-04-07T15:21:08.805075Z","end":"2026-04-07T15:21:09.0728Z","steps":["trace[35192116] 'read index received' (duration: 228.599981ms)","trace[35192116] 'applied index is now lower than readState.Index' (duration: 39.123436ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:21:09.07369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.406631ms","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-04-07T15:21:09.073806Z","caller":"traceutil/trace.go:171","msg":"trace[1545532107] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4690; }","duration":"268.766067ms","start":"2026-04-07T15:21:08.805021Z","end":"2026-04-07T15:21:09.073787Z","steps":["trace[1545532107] 'agreement among raft nodes before linearized reading' (duration: 268.39586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:13.803629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.722158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:21:13.803736Z","caller":"traceutil/trace.go:171","msg":"trace[216327085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4767; }","duration":"624.878162ms","start":"2026-04-07T15:21:13.178826Z","end":"2026-04-07T15:21:13.803704Z","steps":["trace[216327085] 'range keys from in-memory index tree' (duration: 624.610206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:13.803786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:13.178802Z","time spent":"624.972204ms","remote":"127.0.0.1:55734","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-04-07T15:21:13.803907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.220104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-04-07T15:21:13.803963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.762962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-90fe4b84-5860-4816-9426-99c90119d98c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-07T15:21:13.803991Z","caller":"traceutil/trace.go:171","msg":"trace[1697333439] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4767; }","duration":"710.339216ms","start":"2026-04-07T15:21:13.093627Z","end":"2026-04-07T15:21:13.803966Z","steps":["trace[1697333439] 'range keys from in-memory index tree' (duration: 710.00692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:13.804044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:13.093605Z","time spent":"710.430108ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-07T15:21:13.804046Z","caller":"traceutil/trace.go:171","msg":"trace[811574765] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-90fe4b84-5860-4816-9426-99c90119d98c; range_end:; response_count:1; response_revision:4767; }","duration":"666.908595ms","start":"2026-04-07T15:21:13.137116Z","end":"2026-04-07T15:21:13.804025Z","steps":["trace[811574765] 'range keys from in-memory index tree' (duration: 666.583108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:13.804104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:13.137095Z","time spent":"666.986457ms","remote":"127.0.0.1:55910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-90fe4b84-5860-4816-9426-99c90119d98c\" "} {"level":"warn","ts":"2026-04-07T15:21:13.803967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.453962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:21:13.804268Z","caller":"traceutil/trace.go:171","msg":"trace[835457706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4767; }","duration":"385.817389ms","start":"2026-04-07T15:21:13.418436Z","end":"2026-04-07T15:21:13.804254Z","steps":["trace[835457706] 'range keys from in-memory index tree' (duration: 385.212348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:13.8043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:13.418413Z","time spent":"385.878051ms","remote":"127.0.0.1:55724","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-04-07T15:21:13.80422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.301604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-90fe4b84-5860-4816-9426-99c90119d98c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-07T15:21:13.804451Z","caller":"traceutil/trace.go:171","msg":"trace[348922318] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-90fe4b84-5860-4816-9426-99c90119d98c; range_end:; response_count:1; response_revision:4767; }","duration":"540.569078ms","start":"2026-04-07T15:21:13.263863Z","end":"2026-04-07T15:21:13.804432Z","steps":["trace[348922318] 'range keys from in-memory index tree' (duration: 540.143651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:13.804537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:13.263841Z","time spent":"540.680711ms","remote":"127.0.0.1:55910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-90fe4b84-5860-4816-9426-99c90119d98c\" "} {"level":"info","ts":"2026-04-07T15:21:13.945693Z","caller":"traceutil/trace.go:171","msg":"trace[1297020431] linearizableReadLoop","detail":"{readStateIndex:4954; appliedIndex:4953; }","duration":"212.421563ms","start":"2026-04-07T15:21:13.73325Z","end":"2026-04-07T15:21:13.945672Z","steps":["trace[1297020431] 'read index received' (duration: 212.27314ms)","trace[1297020431] 'applied index is now lower than readState.Index' (duration: 147.483µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:13.945858Z","caller":"traceutil/trace.go:171","msg":"trace[1082354491] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"295.301017ms","start":"2026-04-07T15:21:13.650428Z","end":"2026-04-07T15:21:13.945729Z","steps":["trace[1082354491] 'process raft request' (duration: 295.087583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:13.945888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.612906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:21:13.945984Z","caller":"traceutil/trace.go:171","msg":"trace[824568966] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:4768; }","duration":"212.764909ms","start":"2026-04-07T15:21:13.733196Z","end":"2026-04-07T15:21:13.945961Z","steps":["trace[824568966] 'agreement among raft nodes before linearized reading' (duration: 212.588266ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:14.115013Z","caller":"traceutil/trace.go:171","msg":"trace[1961277055] linearizableReadLoop","detail":"{readStateIndex:4956; appliedIndex:4954; }","duration":"169.232323ms","start":"2026-04-07T15:21:13.945756Z","end":"2026-04-07T15:21:14.114988Z","steps":["trace[1961277055] 'read index received' (duration: 168.888237ms)","trace[1961277055] 'applied index is now lower than readState.Index' (duration: 342.796µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:14.1152Z","caller":"traceutil/trace.go:171","msg":"trace[796411459] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"304.315147ms","start":"2026-04-07T15:21:13.810857Z","end":"2026-04-07T15:21:14.115172Z","steps":["trace[796411459] 'process raft request' (duration: 304.066552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:14.115298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.996018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T15:21:14.115334Z","caller":"traceutil/trace.go:171","msg":"trace[1104812342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4770; }","duration":"191.045199ms","start":"2026-04-07T15:21:13.924277Z","end":"2026-04-07T15:21:14.115322Z","steps":["trace[1104812342] 'agreement among raft nodes before linearized reading' (duration: 190.967198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:14.115342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:13.810843Z","time spent":"304.416448ms","remote":"127.0.0.1:55910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:21:14.115366Z","caller":"traceutil/trace.go:171","msg":"trace[157925936] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"305.892137ms","start":"2026-04-07T15:21:13.809443Z","end":"2026-04-07T15:21:14.115336Z","steps":["trace[157925936] 'process raft request' (duration: 305.239484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:14.115222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.427158ms","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-04-07T15:21:14.11547Z","caller":"traceutil/trace.go:171","msg":"trace[289805819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4770; }","duration":"201.747734ms","start":"2026-04-07T15:21:13.913707Z","end":"2026-04-07T15:21:14.115454Z","steps":["trace[289805819] 'agreement among raft nodes before linearized reading' (duration: 201.377287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:14.115498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.528466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-07T15:21:14.11556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:13.809419Z","time spent":"306.029899ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:21:14.115601Z","caller":"traceutil/trace.go:171","msg":"trace[1289811241] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4770; }","duration":"183.660538ms","start":"2026-04-07T15:21:13.931922Z","end":"2026-04-07T15:21:14.115582Z","steps":["trace[1289811241] 'agreement among raft nodes before linearized reading' (duration: 183.331702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:14.920136Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764561072096991,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T15:21:15.025718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.365438ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:21:15.025836Z","caller":"traceutil/trace.go:171","msg":"trace[1875615856] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4777; }","duration":"171.49466ms","start":"2026-04-07T15:21:14.854324Z","end":"2026-04-07T15:21:15.025818Z","steps":["trace[1875615856] 'range keys from in-memory index tree' (duration: 171.349198ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:15.02591Z","caller":"traceutil/trace.go:171","msg":"trace[716965469] linearizableReadLoop","detail":"{readStateIndex:4965; appliedIndex:4963; }","duration":"606.234703ms","start":"2026-04-07T15:21:14.41965Z","end":"2026-04-07T15:21:15.025885Z","steps":["trace[716965469] 'read index received' (duration: 578.354383ms)","trace[716965469] 'applied index is now lower than readState.Index' (duration: 27.87924ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:15.026038Z","caller":"traceutil/trace.go:171","msg":"trace[1905960932] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"640.416456ms","start":"2026-04-07T15:21:14.385608Z","end":"2026-04-07T15:21:15.026025Z","steps":["trace[1905960932] 'process raft request' (duration: 612.377492ms)","trace[1905960932] 'compare' (duration: 26.884542ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:21:15.026126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.476669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:21:15.02615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:14.385575Z","time spent":"640.494098ms","remote":"127.0.0.1:55806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:21:15.026174Z","caller":"traceutil/trace.go:171","msg":"trace[2003560676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4779; }","duration":"606.55246ms","start":"2026-04-07T15:21:14.41961Z","end":"2026-04-07T15:21:15.026163Z","steps":["trace[2003560676] 'agreement among raft nodes before linearized reading' (duration: 606.428937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:15.026209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:14.419597Z","time spent":"606.60565ms","remote":"127.0.0.1:55734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T15:21:15.026517Z","caller":"traceutil/trace.go:171","msg":"trace[1250055648] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"227.383896ms","start":"2026-04-07T15:21:14.79912Z","end":"2026-04-07T15:21:15.026503Z","steps":["trace[1250055648] 'process raft request' (duration: 226.650442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:15.026567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.863415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-90fe4b84-5860-4816-9426-99c90119d98c\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-04-07T15:21:15.026659Z","caller":"traceutil/trace.go:171","msg":"trace[1877555098] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-90fe4b84-5860-4816-9426-99c90119d98c; range_end:; response_count:1; response_revision:4779; }","duration":"218.004187ms","start":"2026-04-07T15:21:14.808629Z","end":"2026-04-07T15:21:15.026633Z","steps":["trace[1877555098] 'agreement among raft nodes before linearized reading' (duration: 217.729462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:15.026964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.05937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-07T15:21:15.027007Z","caller":"traceutil/trace.go:171","msg":"trace[1946730907] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4779; }","duration":"368.145881ms","start":"2026-04-07T15:21:14.658851Z","end":"2026-04-07T15:21:15.026997Z","steps":["trace[1946730907] 'agreement among raft nodes before linearized reading' (duration: 368.015219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:15.02702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.175948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-07T15:21:15.027037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:14.658826Z","time spent":"368.203782ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-04-07T15:21:15.027065Z","caller":"traceutil/trace.go:171","msg":"trace[2098942959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4779; }","duration":"491.223019ms","start":"2026-04-07T15:21:14.535829Z","end":"2026-04-07T15:21:15.027052Z","steps":["trace[2098942959] 'agreement among raft nodes before linearized reading' (duration: 491.077747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:15.02711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:14.53576Z","time spent":"491.339831ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T15:21:15.027375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.375137ms","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-04-07T15:21:15.027417Z","caller":"traceutil/trace.go:171","msg":"trace[165248079] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4779; }","duration":"506.471549ms","start":"2026-04-07T15:21:14.520936Z","end":"2026-04-07T15:21:15.027408Z","steps":["trace[165248079] 'agreement among raft nodes before linearized reading' (duration: 505.404009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:15.027448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:14.520907Z","time spent":"506.533121ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T15:21:15.175041Z","caller":"traceutil/trace.go:171","msg":"trace[758449615] transaction","detail":"{read_only:false; response_revision:4781; number_of_response:1; }","duration":"142.320638ms","start":"2026-04-07T15:21:15.032693Z","end":"2026-04-07T15:21:15.175014Z","steps":["trace[758449615] 'process raft request' (duration: 133.202405ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:15.175607Z","caller":"traceutil/trace.go:171","msg":"trace[1034498257] transaction","detail":"{read_only:false; response_revision:4782; number_of_response:1; }","duration":"142.801997ms","start":"2026-04-07T15:21:15.032778Z","end":"2026-04-07T15:21:15.17558Z","steps":["trace[1034498257] 'process raft request' (duration: 142.173066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:16.400172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.598686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-07T15:21:16.400264Z","caller":"traceutil/trace.go:171","msg":"trace[1071654378] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4789; }","duration":"279.742059ms","start":"2026-04-07T15:21:16.120497Z","end":"2026-04-07T15:21:16.400239Z","steps":["trace[1071654378] 'range keys from in-memory index tree' (duration: 279.329881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:16.400425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.37844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T15:21:16.401311Z","caller":"traceutil/trace.go:171","msg":"trace[254047374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4789; }","duration":"262.299829ms","start":"2026-04-07T15:21:16.138992Z","end":"2026-04-07T15:21:16.401292Z","steps":["trace[254047374] 'range keys from in-memory index tree' (duration: 261.071095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:16.400447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.490068ms","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-04-07T15:21:16.401529Z","caller":"traceutil/trace.go:171","msg":"trace[958233821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4789; }","duration":"239.580968ms","start":"2026-04-07T15:21:16.161927Z","end":"2026-04-07T15:21:16.401508Z","steps":["trace[958233821] 'range keys from in-memory index tree' (duration: 238.304743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:16.400675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.030165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T15:21:16.401589Z","caller":"traceutil/trace.go:171","msg":"trace[901718942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4789; }","duration":"242.029604ms","start":"2026-04-07T15:21:16.15955Z","end":"2026-04-07T15:21:16.401579Z","steps":["trace[901718942] 'range keys from in-memory index tree' (duration: 240.26931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:16.400771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.46472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-04-07T15:21:16.401663Z","caller":"traceutil/trace.go:171","msg":"trace[655800217] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4789; }","duration":"245.383268ms","start":"2026-04-07T15:21:16.156269Z","end":"2026-04-07T15:21:16.401652Z","steps":["trace[655800217] 'range keys from in-memory index tree' (duration: 244.326268ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:16.429468Z","caller":"traceutil/trace.go:171","msg":"trace[1341531401] linearizableReadLoop","detail":"{readStateIndex:4976; appliedIndex:4975; }","duration":"202.450742ms","start":"2026-04-07T15:21:16.226979Z","end":"2026-04-07T15:21:16.42943Z","steps":["trace[1341531401] 'read index received' (duration: 202.29862ms)","trace[1341531401] 'applied index is now lower than readState.Index' (duration: 150.942µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:16.429615Z","caller":"traceutil/trace.go:171","msg":"trace[409039519] transaction","detail":"{read_only:false; response_revision:4790; number_of_response:1; }","duration":"239.400405ms","start":"2026-04-07T15:21:16.190171Z","end":"2026-04-07T15:21:16.429572Z","steps":["trace[409039519] 'process raft request' (duration: 239.045819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:16.429693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.708688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-07T15:21:16.429747Z","caller":"traceutil/trace.go:171","msg":"trace[1428640563] 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:4790; }","duration":"202.768049ms","start":"2026-04-07T15:21:16.226962Z","end":"2026-04-07T15:21:16.42973Z","steps":["trace[1428640563] 'agreement among raft nodes before linearized reading' (duration: 202.608406ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:16.560533Z","caller":"traceutil/trace.go:171","msg":"trace[2141787803] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"154.747478ms","start":"2026-04-07T15:21:16.405768Z","end":"2026-04-07T15:21:16.560515Z","steps":["trace[2141787803] 'process raft request' (duration: 154.667896ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:16.560959Z","caller":"traceutil/trace.go:171","msg":"trace[2032657365] linearizableReadLoop","detail":"{readStateIndex:4981; appliedIndex:4976; }","duration":"131.331853ms","start":"2026-04-07T15:21:16.429612Z","end":"2026-04-07T15:21:16.560944Z","steps":["trace[2032657365] 'read index received' (duration: 88.543461ms)","trace[2032657365] 'applied index is now lower than readState.Index' (duration: 42.787211ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:16.561109Z","caller":"traceutil/trace.go:171","msg":"trace[781534501] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"149.561609ms","start":"2026-04-07T15:21:16.411532Z","end":"2026-04-07T15:21:16.561094Z","steps":["trace[781534501] 'process raft request' (duration: 149.293804ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:16.561182Z","caller":"traceutil/trace.go:171","msg":"trace[608950147] transaction","detail":"{read_only:false; response_revision:4793; number_of_response:1; }","duration":"155.343478ms","start":"2026-04-07T15:21:16.405809Z","end":"2026-04-07T15:21:16.561152Z","steps":["trace[608950147] 'process raft request' (duration: 154.670516ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:16.561435Z","caller":"traceutil/trace.go:171","msg":"trace[1846334911] transaction","detail":"{read_only:false; response_revision:4794; number_of_response:1; }","duration":"155.568642ms","start":"2026-04-07T15:21:16.405855Z","end":"2026-04-07T15:21:16.561423Z","steps":["trace[1846334911] 'process raft request' (duration: 154.836869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:16.561596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.669205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:21:16.561688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.050379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-e62d9d82-0835-468a-a565-7dc572ce6210\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-04-07T15:21:16.561741Z","caller":"traceutil/trace.go:171","msg":"trace[2084490515] range","detail":"{range_begin:/registry/persistentvolumes/pvc-e62d9d82-0835-468a-a565-7dc572ce6210; range_end:; response_count:1; response_revision:4795; }","duration":"129.131271ms","start":"2026-04-07T15:21:16.432596Z","end":"2026-04-07T15:21:16.561727Z","steps":["trace[2084490515] 'agreement among raft nodes before linearized reading' (duration: 128.952408ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:16.561701Z","caller":"traceutil/trace.go:171","msg":"trace[651159449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4795; }","duration":"145.811747ms","start":"2026-04-07T15:21:16.415871Z","end":"2026-04-07T15:21:16.561683Z","steps":["trace[651159449] 'agreement among raft nodes before linearized reading' (duration: 145.585833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:16.562029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.280251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-07T15:21:16.562128Z","caller":"traceutil/trace.go:171","msg":"trace[126415670] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4795; }","duration":"200.392624ms","start":"2026-04-07T15:21:16.361687Z","end":"2026-04-07T15:21:16.56208Z","steps":["trace[126415670] 'agreement among raft nodes before linearized reading' (duration: 200.21062ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:16.560536Z","caller":"traceutil/trace.go:171","msg":"trace[476398274] transaction","detail":"{read_only:false; response_revision:4791; number_of_response:1; }","duration":"154.87337ms","start":"2026-04-07T15:21:16.405619Z","end":"2026-04-07T15:21:16.560493Z","steps":["trace[476398274] 'process raft request' (duration: 112.591618ms)","trace[476398274] 'compare' (duration: 42.124819ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:16.775698Z","caller":"traceutil/trace.go:171","msg":"trace[1584108919] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"117.621052ms","start":"2026-04-07T15:21:16.658028Z","end":"2026-04-07T15:21:16.775649Z","steps":["trace[1584108919] 'process raft request' (duration: 88.089712ms)","trace[1584108919] 'compare' (duration: 29.287216ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:16.893192Z","caller":"traceutil/trace.go:171","msg":"trace[2022867838] linearizableReadLoop","detail":"{readStateIndex:4990; appliedIndex:4989; }","duration":"111.21035ms","start":"2026-04-07T15:21:16.781965Z","end":"2026-04-07T15:21:16.893175Z","steps":["trace[2022867838] 'read index received' (duration: 76.496971ms)","trace[2022867838] 'applied index is now lower than readState.Index' (duration: 34.712509ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:21:16.893425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.431454ms","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-04-07T15:21:16.893458Z","caller":"traceutil/trace.go:171","msg":"trace[1491963909] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4804; }","duration":"111.526317ms","start":"2026-04-07T15:21:16.781921Z","end":"2026-04-07T15:21:16.893447Z","steps":["trace[1491963909] 'agreement among raft nodes before linearized reading' (duration: 111.356323ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:16.893562Z","caller":"traceutil/trace.go:171","msg":"trace[378976257] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"111.666519ms","start":"2026-04-07T15:21:16.781859Z","end":"2026-04-07T15:21:16.893526Z","steps":["trace[378976257] 'process raft request' (duration: 106.919139ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:17.295138Z","caller":"traceutil/trace.go:171","msg":"trace[2087457688] linearizableReadLoop","detail":"{readStateIndex:4993; appliedIndex:4992; }","duration":"137.236338ms","start":"2026-04-07T15:21:17.157882Z","end":"2026-04-07T15:21:17.295118Z","steps":["trace[2087457688] 'read index received' (duration: 53.92555ms)","trace[2087457688] 'applied index is now lower than readState.Index' (duration: 83.309818ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:17.295153Z","caller":"traceutil/trace.go:171","msg":"trace[409348473] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"183.201468ms","start":"2026-04-07T15:21:17.111915Z","end":"2026-04-07T15:21:17.295117Z","steps":["trace[409348473] 'process raft request' (duration: 99.87482ms)","trace[409348473] 'compare' (duration: 83.161875ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:17.295442Z","caller":"traceutil/trace.go:171","msg":"trace[1023135200] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"121.895428ms","start":"2026-04-07T15:21:17.173515Z","end":"2026-04-07T15:21:17.295411Z","steps":["trace[1023135200] 'process raft request' (duration: 121.556692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:17.295762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.861309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T15:21:17.295912Z","caller":"traceutil/trace.go:171","msg":"trace[1915131396] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:4808; }","duration":"138.069473ms","start":"2026-04-07T15:21:17.157829Z","end":"2026-04-07T15:21:17.295899Z","steps":["trace[1915131396] 'agreement among raft nodes before linearized reading' (duration: 137.426851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:17.295924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.494219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13294"} {"level":"info","ts":"2026-04-07T15:21:17.295985Z","caller":"traceutil/trace.go:171","msg":"trace[1179627576] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4808; }","duration":"122.612632ms","start":"2026-04-07T15:21:17.17336Z","end":"2026-04-07T15:21:17.295972Z","steps":["trace[1179627576] 'agreement among raft nodes before linearized reading' (duration: 121.936419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:17.295963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.71904ms","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-04-07T15:21:17.296756Z","caller":"traceutil/trace.go:171","msg":"trace[532590759] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4808; }","duration":"116.590597ms","start":"2026-04-07T15:21:17.180139Z","end":"2026-04-07T15:21:17.29673Z","steps":["trace[532590759] 'agreement among raft nodes before linearized reading' (duration: 115.648849ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:17.488758Z","caller":"traceutil/trace.go:171","msg":"trace[657834291] transaction","detail":"{read_only:false; response_revision:4812; number_of_response:1; }","duration":"158.159023ms","start":"2026-04-07T15:21:17.330491Z","end":"2026-04-07T15:21:17.48865Z","steps":["trace[657834291] 'process raft request' (duration: 68.811682ms)","trace[657834291] 'compare' (duration: 88.76555ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:20.156124Z","caller":"traceutil/trace.go:171","msg":"trace[1338457328] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"187.507224ms","start":"2026-04-07T15:21:19.968587Z","end":"2026-04-07T15:21:20.156094Z","steps":["trace[1338457328] 'process raft request' (duration: 125.149123ms)","trace[1338457328] 'compare' (duration: 62.214629ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:21:25.82721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.58054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:21:25.827803Z","caller":"traceutil/trace.go:171","msg":"trace[110317589] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"536.611855ms","start":"2026-04-07T15:21:25.291144Z","end":"2026-04-07T15:21:25.827756Z","steps":["trace[110317589] 'process raft request' (duration: 417.397983ms)","trace[110317589] 'compare' (duration: 118.452028ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:25.827803Z","caller":"traceutil/trace.go:171","msg":"trace[739295510] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"535.148037ms","start":"2026-04-07T15:21:25.292608Z","end":"2026-04-07T15:21:25.827756Z","steps":["trace[739295510] 'process raft request' (duration: 534.891332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:25.829088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:25.291134Z","time spent":"537.845837ms","remote":"127.0.0.1:55902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:21:25.829254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:25.292601Z","time spent":"536.468311ms","remote":"127.0.0.1:55978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:21:25.82787Z","caller":"traceutil/trace.go:171","msg":"trace[60148358] linearizableReadLoop","detail":"{readStateIndex:5122; appliedIndex:5117; }","duration":"501.187808ms","start":"2026-04-07T15:21:25.326663Z","end":"2026-04-07T15:21:25.827851Z","steps":["trace[60148358] 'read index received' (duration: 381.903125ms)","trace[60148358] 'applied index is now lower than readState.Index' (duration: 119.283153ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:25.827895Z","caller":"traceutil/trace.go:171","msg":"trace[514094968] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"535.932151ms","start":"2026-04-07T15:21:25.291949Z","end":"2026-04-07T15:21:25.827882Z","steps":["trace[514094968] 'process raft request' (duration: 535.378811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:25.82963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:25.291929Z","time spent":"537.659753ms","remote":"127.0.0.1:55902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:21:25.827865Z","caller":"traceutil/trace.go:171","msg":"trace[928724925] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"521.045545ms","start":"2026-04-07T15:21:25.306784Z","end":"2026-04-07T15:21:25.827829Z","steps":["trace[928724925] 'process raft request' (duration: 520.881612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:25.829833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:25.306753Z","time spent":"523.048293ms","remote":"127.0.0.1:56156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12009,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:21:25.829879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.122532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-07T15:21:25.829929Z","caller":"traceutil/trace.go:171","msg":"trace[1137755091] 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:4932; }","duration":"497.192243ms","start":"2026-04-07T15:21:25.332719Z","end":"2026-04-07T15:21:25.829912Z","steps":["trace[1137755091] 'agreement among raft nodes before linearized reading' (duration: 496.978308ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:25.827959Z","caller":"traceutil/trace.go:171","msg":"trace[1892290799] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"535.285959ms","start":"2026-04-07T15:21:25.292651Z","end":"2026-04-07T15:21:25.827937Z","steps":["trace[1892290799] 'process raft request' (duration: 534.903062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:25.82999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:25.332712Z","time spent":"497.269414ms","remote":"127.0.0.1:55892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-07T15:21:25.830017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:25.292647Z","time spent":"537.340679ms","remote":"127.0.0.1:55978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:21:25.830174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.997516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:21:25.828101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.430652ms","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-04-07T15:21:25.830223Z","caller":"traceutil/trace.go:171","msg":"trace[163780667] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4932; }","duration":"503.592402ms","start":"2026-04-07T15:21:25.326619Z","end":"2026-04-07T15:21:25.830211Z","steps":["trace[163780667] 'agreement among raft nodes before linearized reading' (duration: 501.285539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:25.830261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:25.3266Z","time spent":"503.652663ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T15:21:25.830421Z","caller":"traceutil/trace.go:171","msg":"trace[1513604778] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:4932; }","duration":"327.24664ms","start":"2026-04-07T15:21:25.503163Z","end":"2026-04-07T15:21:25.83041Z","steps":["trace[1513604778] 'agreement among raft nodes before linearized reading' (duration: 326.973875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:25.830461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:25.503151Z","time spent":"327.298661ms","remote":"127.0.0.1:53190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:21:25.830604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.374826ms","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-04-07T15:21:25.830659Z","caller":"traceutil/trace.go:171","msg":"trace[301759176] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4932; }","duration":"408.453996ms","start":"2026-04-07T15:21:25.422189Z","end":"2026-04-07T15:21:25.830643Z","steps":["trace[301759176] 'agreement among raft nodes before linearized reading' (duration: 408.238523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:25.830702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:25.422174Z","time spent":"408.515788ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T15:21:25.830894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.547292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:21:25.830936Z","caller":"traceutil/trace.go:171","msg":"trace[1988851717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4932; }","duration":"412.629064ms","start":"2026-04-07T15:21:25.418296Z","end":"2026-04-07T15:21:25.830925Z","steps":["trace[1988851717] 'agreement among raft nodes before linearized reading' (duration: 412.563202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:25.830968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:25.418254Z","time spent":"412.708355ms","remote":"127.0.0.1:55724","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-04-07T15:21:30.382724Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764561072097424,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T15:21:30.539318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.654897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:21:30.53938Z","caller":"traceutil/trace.go:171","msg":"trace[243320329] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:4969; }","duration":"738.728768ms","start":"2026-04-07T15:21:29.800636Z","end":"2026-04-07T15:21:30.539365Z","steps":["trace[243320329] 'count revisions from in-memory index tree' (duration: 738.565475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:30.539409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:29.800582Z","time spent":"738.82035ms","remote":"127.0.0.1:53238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:21:30.539664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.115413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:21:30.539721Z","caller":"traceutil/trace.go:171","msg":"trace[1892383799] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:4969; }","duration":"967.199655ms","start":"2026-04-07T15:21:29.572507Z","end":"2026-04-07T15:21:30.539707Z","steps":["trace[1892383799] 'count revisions from in-memory index tree' (duration: 967.040362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:30.539754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:29.572488Z","time spent":"967.256826ms","remote":"127.0.0.1:55572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:21:30.883134Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764561072097424,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T15:21:30.934842Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.088015028s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T15:21:30.935454Z","caller":"traceutil/trace.go:171","msg":"trace[1012504427] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"1.08874266s","start":"2026-04-07T15:21:29.846686Z","end":"2026-04-07T15:21:30.935429Z","steps":["trace[1012504427] 'process raft request' (duration: 1.088532417s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:30.93571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:29.846652Z","time spent":"1.088869653s","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:21:31.457092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.074855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:21:31.457361Z","caller":"traceutil/trace.go:171","msg":"trace[20074207] linearizableReadLoop","detail":"{readStateIndex:5163; appliedIndex:5160; }","duration":"1.575411748s","start":"2026-04-07T15:21:29.881931Z","end":"2026-04-07T15:21:31.457342Z","steps":["trace[20074207] 'read index received' (duration: 1.053113827s)","trace[20074207] 'applied index is now lower than readState.Index' (duration: 522.296941ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:31.45757Z","caller":"traceutil/trace.go:171","msg":"trace[600144088] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"975.924425ms","start":"2026-04-07T15:21:30.481595Z","end":"2026-04-07T15:21:31.457519Z","steps":["trace[600144088] 'process raft request' (duration: 975.602329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:31.457729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:30.481573Z","time spent":"976.075218ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:21:31.457814Z","caller":"traceutil/trace.go:171","msg":"trace[1171744314] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"1.269406405s","start":"2026-04-07T15:21:30.188392Z","end":"2026-04-07T15:21:31.457798Z","steps":["trace[1171744314] 'process raft request' (duration: 979.510425ms)","trace[1171744314] 'compare' (duration: 288.536245ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:21:31.457896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.575899667s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-07T15:21:31.457937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:30.18837Z","time spent":"1.269486376s","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:21:31.458016Z","caller":"traceutil/trace.go:171","msg":"trace[150329510] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4972; }","duration":"1.576122152s","start":"2026-04-07T15:21:29.881875Z","end":"2026-04-07T15:21:31.457997Z","steps":["trace[150329510] 'agreement among raft nodes before linearized reading' (duration: 1.575702263s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:31.458181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:29.881852Z","time spent":"1.576288014s","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T15:21:31.742703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.982176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:21:31.743059Z","caller":"traceutil/trace.go:171","msg":"trace[1190252340] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"592.290099ms","start":"2026-04-07T15:21:31.150739Z","end":"2026-04-07T15:21:31.743029Z","steps":["trace[1190252340] 'process raft request' (duration: 460.845035ms)","trace[1190252340] 'compare' (duration: 130.245463ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:21:31.743266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:31.150707Z","time spent":"592.482203ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:21:31.743355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.449560684s","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":"warn","ts":"2026-04-07T15:21:31.743414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.562920582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-07T15:21:31.743484Z","caller":"traceutil/trace.go:171","msg":"trace[2101855764] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4973; }","duration":"1.563027504s","start":"2026-04-07T15:21:30.180438Z","end":"2026-04-07T15:21:31.743466Z","steps":["trace[2101855764] 'agreement among raft nodes before linearized reading' (duration: 1.562822079s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:31.743515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11099016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T15:21:31.743545Z","caller":"traceutil/trace.go:171","msg":"trace[888484633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4973; }","duration":"1.111069441s","start":"2026-04-07T15:21:30.632466Z","end":"2026-04-07T15:21:31.743536Z","steps":["trace[888484633] 'agreement among raft nodes before linearized reading' (duration: 1.110985269s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:31.743584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:30.632446Z","time spent":"1.111131392s","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T15:21:31.743587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.651577817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:21:31.743541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:30.180416Z","time spent":"1.563111876s","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-04-07T15:21:31.743647Z","caller":"traceutil/trace.go:171","msg":"trace[499643926] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:4973; }","duration":"1.65170138s","start":"2026-04-07T15:21:30.091917Z","end":"2026-04-07T15:21:31.743618Z","steps":["trace[499643926] 'agreement among raft nodes before linearized reading' (duration: 1.651619138s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:31.743687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:30.091873Z","time spent":"1.651804901s","remote":"127.0.0.1:36452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:21:31.743814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111433698s","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-04-07T15:21:31.743847Z","caller":"traceutil/trace.go:171","msg":"trace[2074495235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4973; }","duration":"1.111485439s","start":"2026-04-07T15:21:30.632353Z","end":"2026-04-07T15:21:31.743839Z","steps":["trace[2074495235] 'agreement among raft nodes before linearized reading' (duration: 1.111404137s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:31.743873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:30.632346Z","time spent":"1.111521629s","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T15:21:31.743885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.620466917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:21:31.743923Z","caller":"traceutil/trace.go:171","msg":"trace[1117131705] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:4973; }","duration":"1.620568349s","start":"2026-04-07T15:21:30.123346Z","end":"2026-04-07T15:21:31.743914Z","steps":["trace[1117131705] 'agreement among raft nodes before linearized reading' (duration: 1.620493958s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:31.743813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.856855194s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-07T15:21:31.743985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112725492s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T15:21:31.744015Z","caller":"traceutil/trace.go:171","msg":"trace[903554899] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4973; }","duration":"1.112753453s","start":"2026-04-07T15:21:30.631253Z","end":"2026-04-07T15:21:31.744007Z","steps":["trace[903554899] 'agreement among raft nodes before linearized reading' (duration: 1.112676891s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:31.744044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:30.631193Z","time spent":"1.112844934s","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T15:21:31.744087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.456528522s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-04-07T15:21:31.744125Z","caller":"traceutil/trace.go:171","msg":"trace[78690558] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4973; }","duration":"1.456601843s","start":"2026-04-07T15:21:30.287514Z","end":"2026-04-07T15:21:31.744116Z","steps":["trace[78690558] 'agreement among raft nodes before linearized reading' (duration: 1.456497302s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:31.744154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:30.287495Z","time spent":"1.456652404s","remote":"127.0.0.1:55910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-07T15:21:31.743957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:30.123251Z","time spent":"1.620698211s","remote":"127.0.0.1:42806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":29,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:21:31.744229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119611007s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T15:21:31.744261Z","caller":"traceutil/trace.go:171","msg":"trace[369749633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4973; }","duration":"1.119678899s","start":"2026-04-07T15:21:30.624575Z","end":"2026-04-07T15:21:31.744254Z","steps":["trace[369749633] 'agreement among raft nodes before linearized reading' (duration: 1.119582067s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:31.744256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.857258621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T15:21:31.74432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229913219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:21:31.74434Z","caller":"traceutil/trace.go:171","msg":"trace[1304275462] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4973; }","duration":"1.857385283s","start":"2026-04-07T15:21:29.886935Z","end":"2026-04-07T15:21:31.744321Z","steps":["trace[1304275462] 'agreement among raft nodes before linearized reading' (duration: 1.85721944s)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:31.744364Z","caller":"traceutil/trace.go:171","msg":"trace[1809700862] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4973; }","duration":"1.22997854s","start":"2026-04-07T15:21:30.514375Z","end":"2026-04-07T15:21:31.744354Z","steps":["trace[1809700862] 'agreement among raft nodes before linearized reading' (duration: 1.229926129s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:31.744391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:29.886922Z","time spent":"1.857458765s","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:21:31.744399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:30.514364Z","time spent":"1.23002702s","remote":"127.0.0.1:55464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-04-07T15:21:31.74406Z","caller":"traceutil/trace.go:171","msg":"trace[1827313863] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4973; }","duration":"1.857092358s","start":"2026-04-07T15:21:29.886908Z","end":"2026-04-07T15:21:31.744Z","steps":["trace[1827313863] 'agreement among raft nodes before linearized reading' (duration: 1.856586409s)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:31.743247Z","caller":"traceutil/trace.go:171","msg":"trace[546038150] linearizableReadLoop","detail":"{readStateIndex:5164; appliedIndex:5163; }","duration":"285.760395ms","start":"2026-04-07T15:21:31.4574Z","end":"2026-04-07T15:21:31.743161Z","steps":["trace[546038150] 'read index received' (duration: 154.031827ms)","trace[546038150] 'applied index is now lower than readState.Index' (duration: 131.727078ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:21:31.744546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:29.886894Z","time spent":"1.857644018s","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T15:21:31.744286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:30.624504Z","time spent":"1.11977692s","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T15:21:31.744279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327513497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:21:31.744614Z","caller":"traceutil/trace.go:171","msg":"trace[1496821151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4973; }","duration":"1.327866924s","start":"2026-04-07T15:21:30.416736Z","end":"2026-04-07T15:21:31.744603Z","steps":["trace[1496821151] 'agreement among raft nodes before linearized reading' (duration: 1.327509428s)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:31.743426Z","caller":"traceutil/trace.go:171","msg":"trace[902944968] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4973; }","duration":"1.449652666s","start":"2026-04-07T15:21:30.293752Z","end":"2026-04-07T15:21:31.743405Z","steps":["trace[902944968] 'agreement among raft nodes before linearized reading' (duration: 1.449443532s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:31.744653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:30.416725Z","time spent":"1.327918855s","remote":"127.0.0.1:55734","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-04-07T15:21:31.744676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:30.293746Z","time spent":"1.450919769s","remote":"127.0.0.1:55892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-04-07T15:21:31.74474Z","caller":"traceutil/trace.go:171","msg":"trace[56031513] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"281.314924ms","start":"2026-04-07T15:21:31.463413Z","end":"2026-04-07T15:21:31.744728Z","steps":["trace[56031513] 'process raft request' (duration: 279.622653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:31.744752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.870563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:21:31.7448Z","caller":"traceutil/trace.go:171","msg":"trace[402222633] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:4974; }","duration":"199.969165ms","start":"2026-04-07T15:21:31.544818Z","end":"2026-04-07T15:21:31.744787Z","steps":["trace[402222633] 'agreement among raft nodes before linearized reading' (duration: 199.868814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:31.744323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.518017889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:21:31.74494Z","caller":"traceutil/trace.go:171","msg":"trace[1317391077] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:4973; }","duration":"1.518668181s","start":"2026-04-07T15:21:30.226262Z","end":"2026-04-07T15:21:31.74493Z","steps":["trace[1317391077] 'agreement among raft nodes before linearized reading' (duration: 1.51802552s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:31.744977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:30.226247Z","time spent":"1.518716862s","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":30,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"info","ts":"2026-04-07T15:21:31.925778Z","caller":"traceutil/trace.go:171","msg":"trace[2118172846] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"173.225268ms","start":"2026-04-07T15:21:31.752529Z","end":"2026-04-07T15:21:31.925754Z","steps":["trace[2118172846] 'process raft request' (duration: 173.078505ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:31.925869Z","caller":"traceutil/trace.go:171","msg":"trace[106684092] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"173.348259ms","start":"2026-04-07T15:21:31.752487Z","end":"2026-04-07T15:21:31.925835Z","steps":["trace[106684092] 'process raft request' (duration: 105.778433ms)","trace[106684092] 'compare' (duration: 67.077326ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:31.926649Z","caller":"traceutil/trace.go:171","msg":"trace[543463726] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"173.701416ms","start":"2026-04-07T15:21:31.75291Z","end":"2026-04-07T15:21:31.926612Z","steps":["trace[543463726] 'process raft request' (duration: 172.914952ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:31.926783Z","caller":"traceutil/trace.go:171","msg":"trace[1754791052] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"174.211355ms","start":"2026-04-07T15:21:31.752553Z","end":"2026-04-07T15:21:31.926764Z","steps":["trace[1754791052] 'process raft request' (duration: 173.156486ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:31.927802Z","caller":"traceutil/trace.go:171","msg":"trace[1657064602] linearizableReadLoop","detail":"{readStateIndex:5173; appliedIndex:5166; }","duration":"134.353826ms","start":"2026-04-07T15:21:31.793379Z","end":"2026-04-07T15:21:31.927733Z","steps":["trace[1657064602] 'read index received' (duration: 64.806434ms)","trace[1657064602] 'applied index is now lower than readState.Index' (duration: 69.545612ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:21:31.928083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.328827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:21:31.9297Z","caller":"traceutil/trace.go:171","msg":"trace[1376052038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4982; }","duration":"175.970218ms","start":"2026-04-07T15:21:31.753708Z","end":"2026-04-07T15:21:31.929678Z","steps":["trace[1376052038] 'agreement among raft nodes before linearized reading' (duration: 174.140534ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:31.928333Z","caller":"traceutil/trace.go:171","msg":"trace[570830300] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"174.821566ms","start":"2026-04-07T15:21:31.753463Z","end":"2026-04-07T15:21:31.928284Z","steps":["trace[570830300] 'process raft request' (duration: 174.077103ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:31.928499Z","caller":"traceutil/trace.go:171","msg":"trace[1465898793] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"172.522354ms","start":"2026-04-07T15:21:31.755951Z","end":"2026-04-07T15:21:31.928474Z","steps":["trace[1465898793] 'process raft request' (duration: 171.665878ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:31.928699Z","caller":"traceutil/trace.go:171","msg":"trace[1837925122] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"175.256145ms","start":"2026-04-07T15:21:31.753416Z","end":"2026-04-07T15:21:31.928672Z","steps":["trace[1837925122] 'process raft request' (duration: 172.486074ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:32.163664Z","caller":"traceutil/trace.go:171","msg":"trace[2030705502] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"221.146765ms","start":"2026-04-07T15:21:31.942488Z","end":"2026-04-07T15:21:32.163634Z","steps":["trace[2030705502] 'process raft request' (duration: 133.193612ms)","trace[2030705502] 'compare' (duration: 87.723249ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:32.164085Z","caller":"traceutil/trace.go:171","msg":"trace[2046920776] linearizableReadLoop","detail":"{readStateIndex:5177; appliedIndex:5174; }","duration":"170.550612ms","start":"2026-04-07T15:21:31.993435Z","end":"2026-04-07T15:21:32.163986Z","steps":["trace[2046920776] 'read index received' (duration: 11.631282ms)","trace[2046920776] 'applied index is now lower than readState.Index' (duration: 158.918389ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:32.164435Z","caller":"traceutil/trace.go:171","msg":"trace[529479172] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"221.177597ms","start":"2026-04-07T15:21:31.94323Z","end":"2026-04-07T15:21:32.164408Z","steps":["trace[529479172] 'process raft request' (duration: 220.336671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:32.164431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.978119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-04-07T15:21:32.164512Z","caller":"traceutil/trace.go:171","msg":"trace[1536089502] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4986; }","duration":"171.104922ms","start":"2026-04-07T15:21:31.993384Z","end":"2026-04-07T15:21:32.164489Z","steps":["trace[1536089502] 'agreement among raft nodes before linearized reading' (duration: 170.856967ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:32.1647Z","caller":"traceutil/trace.go:171","msg":"trace[1133639135] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"220.913082ms","start":"2026-04-07T15:21:31.94363Z","end":"2026-04-07T15:21:32.164543Z","steps":["trace[1133639135] 'process raft request' (duration: 220.24248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:33.169716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.492727ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T15:21:33.169815Z","caller":"traceutil/trace.go:171","msg":"trace[1506368563] linearizableReadLoop","detail":"{readStateIndex:5178; appliedIndex:5177; }","duration":"259.028407ms","start":"2026-04-07T15:21:32.910771Z","end":"2026-04-07T15:21:33.1698Z","steps":["trace[1506368563] 'read index received' (duration: 45.101µs)","trace[1506368563] 'applied index is now lower than readState.Index' (duration: 258.981936ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:21:33.169897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.116459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:21:33.169933Z","caller":"traceutil/trace.go:171","msg":"trace[797209703] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:4986; }","duration":"259.19794ms","start":"2026-04-07T15:21:32.910724Z","end":"2026-04-07T15:21:33.169922Z","steps":["trace[797209703] 'agreement among raft nodes before linearized reading' (duration: 259.115949ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:33.648352Z","caller":"traceutil/trace.go:171","msg":"trace[1549706702] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"183.636991ms","start":"2026-04-07T15:21:33.464689Z","end":"2026-04-07T15:21:33.648326Z","steps":["trace[1549706702] 'process raft request' (duration: 183.415217ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:33.648501Z","caller":"traceutil/trace.go:171","msg":"trace[1862120974] linearizableReadLoop","detail":"{readStateIndex:5180; appliedIndex:5178; }","duration":"119.081807ms","start":"2026-04-07T15:21:33.529389Z","end":"2026-04-07T15:21:33.64847Z","steps":["trace[1862120974] 'read index received' (duration: 118.661319ms)","trace[1862120974] 'applied index is now lower than readState.Index' (duration: 418.328µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:33.648538Z","caller":"traceutil/trace.go:171","msg":"trace[1383261271] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"183.810085ms","start":"2026-04-07T15:21:33.464717Z","end":"2026-04-07T15:21:33.648527Z","steps":["trace[1383261271] 'process raft request' (duration: 183.55415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:33.648667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.26201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:21:33.648714Z","caller":"traceutil/trace.go:171","msg":"trace[867883007] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:4988; }","duration":"119.364483ms","start":"2026-04-07T15:21:33.529334Z","end":"2026-04-07T15:21:33.648698Z","steps":["trace[867883007] 'agreement among raft nodes before linearized reading' (duration: 119.269781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:34.299162Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764561072097466,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T15:21:34.368009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.51808ms","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-04-07T15:21:34.368063Z","caller":"traceutil/trace.go:171","msg":"trace[1482221064] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4988; }","duration":"618.622062ms","start":"2026-04-07T15:21:33.749429Z","end":"2026-04-07T15:21:34.368051Z","steps":["trace[1482221064] 'range keys from in-memory index tree' (duration: 618.374908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:34.368085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:33.749408Z","time spent":"618.672923ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T15:21:34.420177Z","caller":"traceutil/trace.go:171","msg":"trace[1933550273] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"668.817205ms","start":"2026-04-07T15:21:33.751305Z","end":"2026-04-07T15:21:34.420122Z","steps":["trace[1933550273] 'process raft request' (duration: 668.640982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:34.420363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:33.751288Z","time spent":"668.996588ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:21:34.420404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.29214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T15:21:34.42046Z","caller":"traceutil/trace.go:171","msg":"trace[1425555192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4989; }","duration":"621.382581ms","start":"2026-04-07T15:21:33.799063Z","end":"2026-04-07T15:21:34.420446Z","steps":["trace[1425555192] 'agreement among raft nodes before linearized reading' (duration: 621.183958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:34.420494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:33.799049Z","time spent":"621.437252ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T15:21:34.420144Z","caller":"traceutil/trace.go:171","msg":"trace[1224855140] linearizableReadLoop","detail":"{readStateIndex:5181; appliedIndex:5180; }","duration":"620.995075ms","start":"2026-04-07T15:21:33.799088Z","end":"2026-04-07T15:21:34.420083Z","steps":["trace[1224855140] 'read index received' (duration: 620.923204ms)","trace[1224855140] 'applied index is now lower than readState.Index' (duration: 70.921µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:21:34.434982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.729691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-07T15:21:34.435095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.863633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T15:21:34.435153Z","caller":"traceutil/trace.go:171","msg":"trace[1879423375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4990; }","duration":"497.968645ms","start":"2026-04-07T15:21:33.937171Z","end":"2026-04-07T15:21:34.43514Z","steps":["trace[1879423375] 'agreement among raft nodes before linearized reading' (duration: 497.783212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:34.435183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:33.93715Z","time spent":"498.027226ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T15:21:34.435059Z","caller":"traceutil/trace.go:171","msg":"trace[2062241044] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4990; }","duration":"503.849022ms","start":"2026-04-07T15:21:33.931187Z","end":"2026-04-07T15:21:34.435036Z","steps":["trace[2062241044] 'agreement among raft nodes before linearized reading' (duration: 503.648028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:34.435243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.140077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-07T15:21:34.435352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:33.931151Z","time spent":"504.186998ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T15:21:34.434982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.6825ms","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-04-07T15:21:34.435419Z","caller":"traceutil/trace.go:171","msg":"trace[1805178933] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4990; }","duration":"498.188818ms","start":"2026-04-07T15:21:33.937206Z","end":"2026-04-07T15:21:34.435395Z","steps":["trace[1805178933] 'agreement among raft nodes before linearized reading' (duration: 497.662239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:34.435453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:33.937194Z","time spent":"498.250539ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T15:21:34.435421Z","caller":"traceutil/trace.go:171","msg":"trace[1633247464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4990; }","duration":"499.250648ms","start":"2026-04-07T15:21:33.936072Z","end":"2026-04-07T15:21:34.435323Z","steps":["trace[1633247464] 'agreement among raft nodes before linearized reading' (duration: 499.015894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:34.435525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:33.936061Z","time spent":"499.451282ms","remote":"127.0.0.1:55976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T15:22:03.801071Z","caller":"traceutil/trace.go:171","msg":"trace[1431739924] transaction","detail":"{read_only:false; response_revision:5269; number_of_response:1; }","duration":"273.938561ms","start":"2026-04-07T15:22:03.527096Z","end":"2026-04-07T15:22:03.801035Z","steps":["trace[1431739924] 'process raft request' (duration: 251.099182ms)","trace[1431739924] 'compare' (duration: 22.491473ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:22:03.912858Z","caller":"traceutil/trace.go:171","msg":"trace[643768335] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"105.522949ms","start":"2026-04-07T15:22:03.807298Z","end":"2026-04-07T15:22:03.91282Z","steps":["trace[643768335] 'process raft request' (duration: 104.260238ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:22:03.913093Z","caller":"traceutil/trace.go:171","msg":"trace[567184679] transaction","detail":"{read_only:false; response_revision:5272; number_of_response:1; }","duration":"100.23571ms","start":"2026-04-07T15:22:03.812845Z","end":"2026-04-07T15:22:03.913081Z","steps":["trace[567184679] 'process raft request' (duration: 99.907104ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:22:27.773511Z","caller":"traceutil/trace.go:171","msg":"trace[612160814] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"107.614145ms","start":"2026-04-07T15:22:27.665869Z","end":"2026-04-07T15:22:27.773483Z","steps":["trace[612160814] 'process raft request' (duration: 107.377871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:22:28.213866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.811816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:22:28.213938Z","caller":"traceutil/trace.go:171","msg":"trace[1347444943] transaction","detail":"{read_only:false; response_revision:5434; number_of_response:1; }","duration":"175.664919ms","start":"2026-04-07T15:22:28.038259Z","end":"2026-04-07T15:22:28.213924Z","steps":["trace[1347444943] 'process raft request' (duration: 70.725361ms)","trace[1347444943] 'compare' (duration: 104.635834ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:22:44.387594Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2913} {"level":"info","ts":"2026-04-07T15:22:44.469845Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2913,"took":"80.877453ms","hash":247793245,"current-db-size-bytes":53862400,"current-db-size":"54 MB","current-db-size-in-use-bytes":29159424,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T15:22:44.469901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":247793245,"revision":2913,"compact-revision":-1} {"level":"info","ts":"2026-04-07T15:25:13.809093Z","caller":"traceutil/trace.go:171","msg":"trace[1549749448] linearizableReadLoop","detail":"{readStateIndex:6820; appliedIndex:6819; }","duration":"193.533101ms","start":"2026-04-07T15:25:13.615535Z","end":"2026-04-07T15:25:13.809068Z","steps":["trace[1549749448] 'read index received' (duration: 191.707947ms)","trace[1549749448] 'applied index is now lower than readState.Index' (duration: 1.823574ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:25:13.809334Z","caller":"traceutil/trace.go:171","msg":"trace[785369629] transaction","detail":"{read_only:false; response_revision:6576; number_of_response:1; }","duration":"212.002203ms","start":"2026-04-07T15:25:13.597222Z","end":"2026-04-07T15:25:13.809224Z","steps":["trace[785369629] 'process raft request' (duration: 210.209839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:25:13.809934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.311397ms","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-04-07T15:25:13.810126Z","caller":"traceutil/trace.go:171","msg":"trace[1129957544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6576; }","duration":"193.582491ms","start":"2026-04-07T15:25:13.616515Z","end":"2026-04-07T15:25:13.810098Z","steps":["trace[1129957544] 'agreement among raft nodes before linearized reading' (duration: 193.107644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:25:13.810225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.659047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T15:25:13.810504Z","caller":"traceutil/trace.go:171","msg":"trace[1523319903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6576; }","duration":"194.981861ms","start":"2026-04-07T15:25:13.615507Z","end":"2026-04-07T15:25:13.810489Z","steps":["trace[1523319903] 'agreement among raft nodes before linearized reading' (duration: 194.600446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:25:43.217166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.829494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:25:43.217688Z","caller":"traceutil/trace.go:171","msg":"trace[194945100] transaction","detail":"{read_only:false; response_revision:6788; number_of_response:1; }","duration":"190.186697ms","start":"2026-04-07T15:25:43.027483Z","end":"2026-04-07T15:25:43.21767Z","steps":["trace[194945100] 'process raft request' (duration: 87.758295ms)","trace[194945100] 'compare' (duration: 101.683732ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:25:43.574818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.10407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:25:43.57494Z","caller":"traceutil/trace.go:171","msg":"trace[1693193293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6788; }","duration":"157.297482ms","start":"2026-04-07T15:25:43.417613Z","end":"2026-04-07T15:25:43.574911Z","steps":["trace[1693193293] 'range keys from in-memory index tree' (duration: 156.986408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:25:43.574961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.901367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107251"} {"level":"info","ts":"2026-04-07T15:25:43.57509Z","caller":"traceutil/trace.go:171","msg":"trace[1631479443] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:6788; }","duration":"223.06772ms","start":"2026-04-07T15:25:43.351996Z","end":"2026-04-07T15:25:43.575063Z","steps":["trace[1631479443] 'range keys from in-memory index tree' (duration: 222.531963ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:17.308636Z","caller":"traceutil/trace.go:171","msg":"trace[1207899468] transaction","detail":"{read_only:false; response_revision:7292; number_of_response:1; }","duration":"108.240634ms","start":"2026-04-07T15:27:17.200317Z","end":"2026-04-07T15:27:17.308557Z","steps":["trace[1207899468] 'process raft request' (duration: 89.798405ms)","trace[1207899468] 'compare' (duration: 17.905652ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:27:24.869469Z","caller":"traceutil/trace.go:171","msg":"trace[1476589097] transaction","detail":"{read_only:false; response_revision:7330; number_of_response:1; }","duration":"197.420419ms","start":"2026-04-07T15:27:24.672021Z","end":"2026-04-07T15:27:24.869441Z","steps":["trace[1476589097] 'process raft request' (duration: 197.368267ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:24.869529Z","caller":"traceutil/trace.go:171","msg":"trace[284988551] transaction","detail":"{read_only:false; response_revision:7329; number_of_response:1; }","duration":"197.955046ms","start":"2026-04-07T15:27:24.67154Z","end":"2026-04-07T15:27:24.869495Z","steps":["trace[284988551] 'process raft request' (duration: 197.588924ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:41.820913Z","caller":"traceutil/trace.go:171","msg":"trace[1219152291] transaction","detail":"{read_only:false; response_revision:7413; number_of_response:1; }","duration":"103.914699ms","start":"2026-04-07T15:27:41.716974Z","end":"2026-04-07T15:27:41.820888Z","steps":["trace[1219152291] 'process raft request' (duration: 103.760675ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:44.393418Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5572} {"level":"info","ts":"2026-04-07T15:27:44.473778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5572,"took":"78.527404ms","hash":216506707,"current-db-size-bytes":53862400,"current-db-size":"54 MB","current-db-size-in-use-bytes":18239488,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-07T15:27:44.473833Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":216506707,"revision":5572,"compact-revision":2913} {"level":"info","ts":"2026-04-07T15:27:47.127192Z","caller":"traceutil/trace.go:171","msg":"trace[1881951278] transaction","detail":"{read_only:false; response_revision:7441; number_of_response:1; }","duration":"100.258602ms","start":"2026-04-07T15:27:47.026903Z","end":"2026-04-07T15:27:47.127161Z","steps":["trace[1881951278] 'process raft request' (duration: 66.440096ms)","trace[1881951278] 'compare' (duration: 33.661192ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:27:59.295588Z","caller":"traceutil/trace.go:171","msg":"trace[1956580385] linearizableReadLoop","detail":"{readStateIndex:7785; appliedIndex:7784; }","duration":"142.735362ms","start":"2026-04-07T15:27:59.152827Z","end":"2026-04-07T15:27:59.295562Z","steps":["trace[1956580385] 'read index received' (duration: 99.598804ms)","trace[1956580385] 'applied index is now lower than readState.Index' (duration: 43.135388ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:27:59.295692Z","caller":"traceutil/trace.go:171","msg":"trace[343317354] transaction","detail":"{read_only:false; response_revision:7503; number_of_response:1; }","duration":"171.839877ms","start":"2026-04-07T15:27:59.123811Z","end":"2026-04-07T15:27:59.29565Z","steps":["trace[343317354] 'process raft request' (duration: 128.602237ms)","trace[343317354] 'compare' (duration: 43.016384ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:27:59.295916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.093635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-07T15:27:59.296041Z","caller":"traceutil/trace.go:171","msg":"trace[87231937] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7503; }","duration":"108.255129ms","start":"2026-04-07T15:27:59.18777Z","end":"2026-04-07T15:27:59.296025Z","steps":["trace[87231937] 'agreement among raft nodes before linearized reading' (duration: 108.009302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:27:59.296526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.678569ms","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-04-07T15:27:59.29663Z","caller":"traceutil/trace.go:171","msg":"trace[78885866] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7503; }","duration":"143.806363ms","start":"2026-04-07T15:27:59.152775Z","end":"2026-04-07T15:27:59.296582Z","steps":["trace[78885866] 'agreement among raft nodes before linearized reading' (duration: 143.231526ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:28:08.101033Z","caller":"traceutil/trace.go:171","msg":"trace[237707177] transaction","detail":"{read_only:false; response_revision:7547; number_of_response:1; }","duration":"107.661079ms","start":"2026-04-07T15:28:07.993351Z","end":"2026-04-07T15:28:08.101012Z","steps":["trace[237707177] 'process raft request' (duration: 107.519365ms)"],"step_count":1}